Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.02.2023 07:47
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 14797524 kB
MemAvailable 30414492 kB
Buffers 0 kB
Cached 16868416 kB
SwapCached 3144 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-02-28 07:46:19
DTS_END                         2023-02-28 07:46:19.5580
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(68:17)
IP                              ::1/38320
TRN_ID                          222838
ATT_ID                          630
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: 8906.97 30510 1068836
customer order: creation 166.03 483 19924
customer order: refuse 14.44 422 1733
order to supplier: creation 45.92 1420 5510
order to supplier: removal 3.27 192528 392
invoice (draft): creation 45.34 3210 5441
invoice (draft): removal 50.85 1033 6102
invoice accept: apply 40.63 26277 4875
invoice accept: cancel 45.47 1431 5456
customer reserve: creation 3751.83 248 450220
customer reserve: removal 4451.60 112 534192
realization accept: apply 82.68 295 9921
realization accept: cancel 80.33 172 9640
payment from customer: creation 56.68 189 6802
payment from customer: removal 5.58 149 670
payment to supplier: creation 50.14 204 6017
payment to supplier: removal 3.43 128 411
service: total inventory turnovers 3.63 4829 435
service: total monetary turnovers 8.13 390 975
service: aggregate perf. data 0.96 17982 115
service: refresh index statistics 0.04 358694 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-02-2023 07:47:36 to 28-02-2023 07:47:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10600
TEST_TIME 2 8188
TEST_TIME 3 6469
TEST_TIME 4 7060
TEST_TIME 5 7212
TEST_TIME 6 7166
TEST_TIME 7 7117
TEST_TIME 8 7226
TEST_TIME 9 7324
TEST_TIME 10 7262
TEST_TIME 11 7187
TEST_TIME 12 7488
TEST_TIME 13 7671
TEST_TIME 14 7656
TEST_TIME 15 7624
TEST_TIME 16 7867
TEST_TIME 17 7890
TEST_TIME 18 7896
TEST_TIME 19 8120
TEST_TIME 20 8162
TEST_TIME 21 8184
TEST_TIME 22 8244
TEST_TIME 23 8265
TEST_TIME 24 8474
TEST_TIME 25 8641
TEST_TIME 26 8919
TEST_TIME 27 9005
TEST_TIME 28 9041
TEST_TIME 29 9354
TEST_TIME 30 9860
TEST_TIME 31 9782
TEST_TIME 32 9824
TEST_TIME 33 10134
TEST_TIME 34 10405
TEST_TIME 35 10951
TEST_TIME 36 11520
TEST_TIME 37 11329
TEST_TIME 38 11052
TEST_TIME 39 10829
TEST_TIME 40 10768
TEST_TIME 41 10795
TEST_TIME 42 10626
TEST_TIME 43 10565
TEST_TIME 44 10536
TEST_TIME 45 10362
TEST_TIME 46 10248
TEST_TIME 47 10169
TEST_TIME 48 10048
TEST_TIME 49 10104
TEST_TIME 50 10023
TEST_TIME 51 9923
TEST_TIME 52 9772
TEST_TIME 53 9727
TEST_TIME 54 9629
TEST_TIME 55 9654
TEST_TIME 56 9608
TEST_TIME 57 9549
TEST_TIME 58 9492
TEST_TIME 59 9480
TEST_TIME 60 9493
TEST_TIME 61 9505
TEST_TIME 62 9588
TEST_TIME 63 9614
TEST_TIME 64 9540
TEST_TIME 65 9527
TEST_TIME 66 9473
TEST_TIME 67 9581
TEST_TIME 68 9550
TEST_TIME 69 9449
TEST_TIME 70 9359
TEST_TIME 71 9277
TEST_TIME 72 9332
TEST_TIME 73 9277
TEST_TIME 74 9236
TEST_TIME 75 9196
TEST_TIME 76 9130
TEST_TIME 77 9058
TEST_TIME 78 9036
TEST_TIME 79 9030
TEST_TIME 80 9020
TEST_TIME 81 8966
TEST_TIME 82 8942
TEST_TIME 83 8985
TEST_TIME 84 8967
TEST_TIME 85 8955
TEST_TIME 86 8920
TEST_TIME 87 8930
TEST_TIME 88 8915
TEST_TIME 89 8998
TEST_TIME 90 9102
TEST_TIME 91 9090
TEST_TIME 92 9165
TEST_TIME 93 9174
TEST_TIME 94 9089
TEST_TIME 95 9174
TEST_TIME 96 9327
TEST_TIME 97 9291
TEST_TIME 98 9241
TEST_TIME 99 9285
TEST_TIME 100 9252
TEST_TIME 101 9237
TEST_TIME 102 9244
TEST_TIME 103 9234
TEST_TIME 104 9222
TEST_TIME 105 9236
TEST_TIME 106 9242
TEST_TIME 107 9213
TEST_TIME 108 9243
TEST_TIME 109 9222
TEST_TIME 110 9153
TEST_TIME 111 9101
TEST_TIME 112 9069
TEST_TIME 113 9087
TEST_TIME 114 9074
TEST_TIME 115 9048
TEST_TIME 116 9017
TEST_TIME 117 8998
TEST_TIME 118 8967
TEST_TIME 119 8988
TEST_TIME 120 8931

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2023 07:47:40 to 28-02-2023 07:47:44.

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 1482138 1482138 0 0.00 0 32826 14 0 0
doc_list_biud 1482132 1482132 0 0.00 0 42258 46 0 0
sp_add_invoice_to_stock 5009 4875 134 2.67 25 226683 26278 0 134
sp_cancel_adding_invoice 5456 5456 0 0.00 6 80240 1431 0 0
sp_cancel_client_order 1733 1733 0 0.00 7 12396 422 0 0
sp_cancel_customer_reserve 534192 534192 0 0.00 1 43069 113 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 5886 149 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 5326 128 0 0
sp_cancel_supplier_invoice 6111 6102 9 0.14 3 41613 1033 0 9
sp_cancel_supplier_order 392 392 0 0.00 9 1560850 192528 0 0
sp_cancel_write_off 9640 9640 0 0.00 1 13610 173 0 0
sp_client_order 19924 19924 0 0.00 6 26770 484 0 0
sp_customer_reserve 450220 450220 0 0.00 0 68371 249 0 0
sp_fill_shopping_cart 30861 30861 0 0.00 1 13131 97 0 0
sp_fill_shopping_cart_clo_res 450177 450177 0 0.00 0 15268 19 0 0
sp_get_clo_for_invoice 4832 4832 0 0.00 0 31155 274 0 0
sp_kill_cost_storno 16177 16177 0 0.00 0 30206 84 0 0
sp_kill_qstorno_ret_qs2qd 1084518 1084518 0 0.00 0 41606 47 0 0
sp_kill_qty_storno 572266 572266 0 0.00 0 42258 110 0 0
sp_lock_selected_doc 20489 20489 0 0.00 0 6199 8 0 0
sp_make_cost_storno 27572 27572 0 0.00 0 28226 83 0 0
sp_multiply_rows_for_pdistr 27570 27570 0 0.00 0 9270 39 0 0
sp_multiply_rows_for_qdistr 19920 19920 0 0.00 0 9324 174 0 0
sp_pay_from_customer 6802 6802 0 0.00 5 14748 190 0 0
sp_pay_to_supplier 6017 6017 0 0.00 6 15480 205 0 0
sp_payment_common 12819 12819 0 0.00 2 14738 49 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 1 12386 342 0 0
sp_qd_handle_on_invoice_upd_sts 10286 10286 0 0.00 1 26956 604 0 0
sp_qd_handle_on_reserve_upd_sts 9921 9921 0 0.00 0 19904 68 0 0
sp_reserve_write_off 9921 9921 0 0.00 8 28243 295 0 0
sp_supplier_invoice 5441 5441 0 0.00 8 112302 3210 0 0
sp_supplier_order 5510 5510 0 0.00 7 34677 1421 0 0
srv_aggregate_perf_data 115 115 0 0.00 2393 170915 17982 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 761 121651 35516 0 0
srv_find_qd_qs_mism 1020275 1020275 0 0.00 0 10793 10 0 0
srv_make_invnt_saldo 435 435 0 0.00 73 129486 4829 0 0
srv_make_money_saldo 975 975 0 0.00 1 12378 390 0 0
srv_recalc_idx_stat 5 5 0 0.00 141541 548010 358695 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4875 4875 0 0.00 1 12613 1063 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 8476 558 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 1189 29 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 4665 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1530 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1961 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 4307 56 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 97703 8115 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2192 22 0 0
t$perf-norm:sp_client_order 19924 19924 0 0.00 0 11381 37 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 1505 14 0 0
t$perf-norm:sp_pay_from_customer 6802 6802 0 0.00 0 10250 24 0 0
t$perf-norm:sp_pay_to_supplier 6017 6017 0 0.00 0 10113 22 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 9219 24 0 0
t$perf-norm:sp_supplier_invoice 5441 5441 0 0.00 0 5341 34 0 0
t$perf-norm:sp_supplier_order 5510 5510 0 0.00 0 12624 35 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 243 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 2260 48 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 3730 21 0 0
t$perf-norm:srv_make_money_saldo 975 975 0 0.00 0 2095 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 161 65 0 0
v_add_invoice_to_stock 9928 9660 268 2.69 0 6155 24 0 268
v_all_customers 36206 36206 0 0.00 0 71 4 0 0
v_all_suppliers 31118 31118 0 0.00 0 75 5 0 0
v_all_wares 39830 39830 0 0.00 0 60 5 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 3488 21 0 0
v_cancel_client_order 3466 3466 0 0.00 0 3059 12 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 5845 19 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 1 1420 13 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 5196 19 0 0
v_cancel_supplier_invoice 858 840 18 2.09 0 2557 29 0 18
v_cancel_supplier_order 784 784 0 0.00 0 5276 18 0 0
v_cancel_write_off 1960 1960 0 0.00 0 4414 36 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 314 6 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 1654 14 0 0
v_max_id_ord_sup 5439 5439 0 0.00 0 4341 9 0 0
v_max_non_paid_invoice 1404 1404 0 0.00 0 113 9 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 1705 9 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 13010 11 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 1915 12 0 0
v_min_id_ord_sup 5439 5439 0 0.00 0 5344 8 0 0
v_min_non_paid_invoice 6017 6017 0 0.00 0 5301 18 0 0
v_min_non_paid_realizn 6802 6802 0 0.00 0 5016 16 0 0
v_our_firm 10000 10000 0 0.00 0 70 5 0 0
v_reserve_write_off 19842 19842 0 0.00 0 10169 12 0 0
x_lock_depdocs_on_canc_invoice 11138 11138 0 0.00 0 19109 137 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 2429 342 0 0
x_make_qstorno_1000_1200 5510 5510 0 0.00 2 32941 1209 0 0
x_make_qstorno_1200_2000 5441 5441 0 0.00 4 112261 2971 0 0
x_make_qstorno_2100_3300 450220 450220 0 0.00 0 68370 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 07:47:45 to 28-02-2023 07:47:49.

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
05:16:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:17:24 18707 8192000000 9725349536 10025734144 800120704 8.898 100 87 174 0 828902032 7543648 21237680
05:18:50 16392 8192000000 9773152448 10082185216 844658656 9.347 100 89 178 0 877938464 12155392 21124416
05:20:12 16203 8192000000 9806814272 10128433152 876012432 9.660 100 81 162 0 912901824 17682368 19207024
05:21:40 7305 8192000000 9855860608 10192715776 917913056 10.075 100 83 160 0 972009056 36428224 17667776
05:22:51 4777 8192000000 9917476112 10259001344 950047792 10.392 100 88 175 0 1029479216 60606048 18825376
05:24:00 3565 8192000000 9980301920 10336350208 986627216 10.749 100 89 178 0 1098202928 92697920 18877792
05:25:06 1331 8192000000 10006227792 10371391488 1024898560 11.119 100 97 184 2 1125138640 81819904 18420176
05:26:08 1280 8192000000 10012492880 10379116544 1043064096 11.294 100 94 176 1 1129749616 68838832 17846688
05:27:16 1130 8192000000 10024585504 10401267712 1064275472 11.497 100 95 181 1 1146507792 63964576 18267744
05:28:18 1045 8192000000 10053424336 10434605056 1069377184 11.546 100 93 181 0 1173628832 85605984 18645664
05:29:21 898 8192000000 10059885776 10442334208 1075012480 11.600 100 100 199 2 1184379920 89248064 20119376
05:30:22 1106 8192000000 10047560528 10431184896 1078022624 11.629 100 91 171 2 1165324864 70000160 17302080
05:31:24 1002 8192000000 10036533552 10424446976 1084391488 11.689 100 84 149 3 1158106368 57957648 15757232
05:32:26 1325 8192000000 10050472720 10438025216 1090022768 11.743 100 90 177 1 1175193136 67142544 18027824
05:33:28 1170 8192000000 10050594352 10439868416 1090065632 11.743 100 87 163 2 1168559872 61079024 17415216
05:34:30 1295 8192000000 10067596496 10458599424 1096132976 11.801 100 81 152 3 1189363536 76726176 16504384
05:35:32 1800 8192000000 10054003680 10450874368 1095014544 11.790 100 73 140 1 1175373808 65982016 14377248
05:36:35 996 8192000000 10043685632 10434121728 1095629840 11.796 100 95 179 1 1167444080 52289248 19524992
05:37:36 1074 8192000000 10059270784 10446688256 1089267520 11.736 100 96 190 1 1182276576 73518416 19490640
05:38:38 1209 8192000000 10053458528 10437795840 1079918032 11.647 100 96 183 1 1172315968 73846704 18551232
05:39:41 1206 8192000000 10018196544 10405163008 1074073872 11.591 100 86 162 5 1141099568 51171568 15854128
05:40:43 876 8192000000 10013537232 10394382336 1052865744 11.388 100 91 180 1 1137195152 64806176 19523232
05:41:45 1485 8192000000 9954239456 10330591232 1030056800 11.169 100 79 145 0 1081018192 36544448 14416944
05:42:47 7661 8192000000 9986725680 10361405440 1023853760 11.109 99 72 139 1 1104149264 66711280 13584224
05:43:58 1117 8192000000 9983493360 10355089408 1036814832 11.234 100 91 176 1 1106703200 51757872 18130496
05:44:59 1120 8192000000 9981256928 10351452160 1018689152 11.059 100 96 185 2 1107526016 68861392 19975472
05:46:01 2980 8192000000 9986459472 10369507328 1046461776 11.327 100 64 106 2 1105803184 49713760 9627648
05:47:09 26139 8192000000 10021749904 10399739904 1052909840 11.389 100 76 144 2 1134631680 66834208 14887632
05:48:40 9839 8192000000 10048383072 10428334080 1072224272 11.573 100 87 165 3 1166723712 77778112 16721328
05:49:53 1699 8192000000 10047659616 10431320064 1081792784 11.665 100 71 133 2 1166585264 71184992 13607488
05:50:58 1895 8192000000 10097450768 10487480320 1101442576 11.851 100 91 166 0 1216001888 96294048 18265264
05:52:03 3043 8192000000 10088897952 10486759424 1108451584 11.918 100 66 125 1 1205847136 84829552 12566000
05:53:15 1281 8192000000 10128009440 10520080384 1118373792 12.012 100 85 165 0 1246033392 111159584 16500016
05:54:22 2696 8192000000 10152023504 10550202368 1126471616 12.088 100 91 178 0 1264744112 118994800 19277696
05:55:28 14853 8192000000 10173704880 10580017152 1142250528 12.237 100 65 123 1 1294188224 138891600 13046096
05:56:50 1982 8192000000 10206046464 10605797376 1161104944 12.414 100 90 172 0 1320555152 140160752 19289456
05:57:57 3426 8192000000 10254373904 10656772096 1166283792 12.462 100 76 141 0 1370714112 188304656 16125664
05:59:03 750 8192000000 10264667344 10685050880 1189017104 12.674 100 97 184 0 1377062496 166971744 21073648
06:00:05 777 8192000000 10274394704 10671386624 1168074720 12.479 99 95 185 1 1384734736 195488176 21171840
06:01:08 844 8192000000 10315479888 10718396416 1180977760 12.599 100 93 174 0 1423447088 223451040 19018288
06:02:12 3132 8192000000 10350392464 10789007360 1186160800 12.648 100 90 172 0 1456709616 250315376 20233440
06:03:18 1441 8192000000 10358026000 10768789504 1184892688 12.636 100 93 176 0 1466632400 261238672 20501040
06:04:22 1127 8192000000 10377336656 10784493568 1176112000 12.554 100 91 173 0 1479056032 281370064 21573968
06:05:24 799 8192000000 10414466720 10824491008 1189297104 12.677 100 95 186 1 1518330128 306474928 22558096
06:06:29 992 8192000000 10450492288 10853462016 1190220272 12.685 100 94 184 1 1554801392 342326480 22254640
06:07:31 1392 8192000000 10498552864 10912247808 1188390688 12.668 100 94 185 0 1604081888 394754672 20936528
06:08:36 1335 8192000000 10515377200 10925928448 1176943408 12.562 100 85 169 0 1622656224 426188528 19524288
06:09:39 1469 8192000000 10547612400 10977341440 1182056192 12.609 100 98 186 2 1646054416 441589264 22408960
06:10:42 1244 8192000000 10524005168 10973048832 1174291616 12.537 100 96 192 2 1630282464 433462880 22527968
06:11:47 750 8192000000 10533987760 10991755264 1179119312 12.582 100 94 179 0 1634645904 433733504 21793088
06:12:50 1533 8192000000 10546166368 10973175808 1183143440 12.620 100 79 154 0 1647762784 446374832 18244512
06:13:53 2588 8192000000 10574827760 11001978880 1183410064 12.622 100 59 116 1 1677807792 480030416 14367312
06:15:02 3660 8192000000 10532333312 11041366016 1177568384 12.568 99 80 157 3 1629345792 433177344 18600064
06:16:11 2831 8192000000 10513785344 10993061888 1192990832 12.711 100 67 124 1 1616731376 407773792 15966752
06:17:22 9095 8192000000 10494910000 10941620224 1184328992 12.631 99 47 94 1 1586479024 390195600 11954432
06:19:09 18783 8192000000 10472422016 10992369664 1140334144 12.219 98 30 60 0 1602543296 453703312 8505840
06:20:30 30396 8192000000 10393866016 10918023168 1148524912 12.296 100 26 52 0 1500071424 344450032 7096480
06:22:09 37936 8192000000 10089553840 10585923584 1125865616 12.082 99 14 28 0 1228667872 99358416 3443840
06:23:49 51420 8192000000 10153676096 10600542208 1147792592 12.289 100 41 72 0 1256552688 100679808 8080288
06:25:46 26237 8192000000 10175534992 10608869376 1146205264 12.274 100 54 102 2 1254437536 97626240 10606032
06:27:13 6504 8192000000 10150128736 10620256256 1130770592 12.129 99 46 87 0 1260983840 119724560 10488688
06:28:24 88519 8192000000 10089399488 10567036928 1109767680 11.930 98 15 31 0 1187276864 74222352 3286832
06:30:56 51230 8192000000 10087070496 10535399424 1123723808 12.062 99 22 39 1 1177945568 49811872 4409888
06:32:48 84481 8192000000 10129961712 10590220288 1132440368 12.144 99 15 30 0 1223589328 87954032 3194928
06:36:22 73721 8192000000 10072041840 10540851200 1144575760 12.259 99 11 22 0 1184265744 37363648 2326336
06:39:13 12159 8192000000 10088214000 10540716032 1153209920 12.340 100 40 80 0 1195773264 34478224 8085120
06:40:26 112441 8192000000 10078993936 10604851200 1124268224 12.067 97 18 33 1 1180250112 52683760 3298128
06:43:20 64801 8192000000 10114021856 10566336512 1111366544 11.945 96 25 50 0 1159124912 42853392 4904976
06:45:32 52388 8192000000 10072954944 10536169472 1104785632 11.883 97 18 34 0 1181520336 73251888 3482816
06:47:29 22867 8192000000 10114446432 10553143296 1128053552 12.103 99 39 78 0 1205241792 68750720 8437520
06:49:00 67607 8192000000 10056489120 10504196096 1088579888 11.729 96 14 28 0 1145713760 54630448 2503424
06:51:10 32210 8192000000 10107017472 10551304192 1127069152 12.094 100 39 78 1 1205614336 71011440 7533744
06:52:49 98771 8192000000 10051075376 10494840832 1089922880 11.742 96 23 46 0 1138261152 43790288 4547984
06:55:34 121650 8192000000 10116517504 10575093760 1121072304 12.037 99 29 58 0 1205888064 78788880 6026880
06:58:36 41512 8192000000 10030889136 10500018176 1091259104 11.755 97 9 18 0 1125085440 31742064 2084272
07:00:21 58848 8192000000 10047801104 10510409728 1076803696 11.617 96 17 31 1 1127902912 47672800 3426416
07:02:21 76553 8192000000 10069996272 10514202624 1068307856 11.536 95 13 26 0 1129683248 58668480 2706912
07:05:20 65875 8192000000 10065183856 10518376448 1110654320 11.939 99 18 36 0 1169383840 54411568 4317952
07:07:31 66691 8192000000 10136648384 10589904896 1113865808 11.969 97 23 44 0 1198889856 79865920 5158128
07:09:46 28916 8192000000 10213443360 10677919744 1152773136 12.336 100 39 78 0 1298320368 136656320 8890912
07:11:25 17419 8192000000 10232451632 10676441088 1148438720 12.295 99 42 83 0 1313024160 155490528 9094912
07:12:46 23902 8192000000 10280390912 10711797760 1167016096 12.469 100 46 87 0 1367694432 191333920 9344416
07:14:11 48357 8192000000 10306337984 10767069184 1160260928 12.406 99 29 57 0 1383811376 216367472 7182976
07:16:05 34476 8192000000 10227696224 10670759936 1132467808 12.145 98 21 42 0 1306980768 169399232 5113728
07:17:40 98531 8192000000 10238236608 10713047040 1144705312 12.260 99 34 68 0 1383802576 231644688 7452576
07:20:19 63473 8192000000 10283104544 10733182976 1153070480 12.338 100 35 70 0 1385458688 223480336 8907872
07:22:24 63598 8192000000 10117287552 10629095424 1138574944 12.202 99 8 16 0 1239850304 99503856 1771504
07:24:29 95006 8192000000 10111076352 10574200832 1128422304 12.106 98 28 54 0 1182222544 47909904 5890336
07:27:05 27689 8192000000 10142977520 10588151808 1154081808 12.348 100 54 108 1 1236332624 70886080 11364736
07:28:35 75595 8192000000 10116217712 10569695232 1155232960 12.359 100 19 36 0 1214724384 55748784 3742640
07:30:53 75823 8192000000 10098464128 10564751360 1108251040 11.916 96 23 45 0 1151520112 38848608 4420464
07:33:13 32186 8192000000 10092900608 10525433856 1128372944 12.106 98 41 80 2 1183392864 46941984 8077936
07:34:46 114094 8192000000 10089440400 10538561536 1128877792 12.111 98 36 72 0 1185781040 49765840 7137408
07:37:41 42123 8192000000 10099372992 10543202304 1144369120 12.257 99 28 51 1 1191688800 40947488 6372192
07:39:23 59061 8192000000 10074966176 10585653248 1121706064 12.043 98 17 35 0 1184559440 59272704 3580672
07:41:23 32972 8192000000 10089644672 10550169600 1144145424 12.255 100 21 35 0 1202678496 54572720 3960352
07:43:00 72533 8192000000 10095479600 10548133888 1102018864 11.857 97 17 34 0 1151568192 45846256 3703072
07:45:14 49285 8192000000 10055823984 10548748288 1082960288 11.676 96 6 12 0 1144928672 60787664 1180720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-02-2023 07:47:51 to 28-02-2023 07:48:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-02-2023 07:48:08 to 28-02-2023 07:48:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          222737
MON$OLDEST_ACTIVE               223294
MON$OLDEST_SNAPSHOT             223294
MON$NEXT_TRANSACTION            223294
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-30 07:30:08.6030 GMT
MON$PAGES                       2088928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Feb 28 07:46:20 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Feb 28 07:46:22 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

28.02.23 07:48:12. There are 3 logs in /var/tmp/logs-oltp40 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
2023-02-28 07:48:14 No severe PSQL-related problems occured
28.02.23 07:48:16. End of report.