Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.2023 16: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 15340340 kB
MemAvailable 30415548 kB
Buffers 0 kB
Cached 16343380 kB
SwapCached 3032 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 2023-03-10 16:38:55
DTS_END                         2023-03-10 16:38:46.4810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40024
TRN_ID                          247580
ATT_ID                          274
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: 9280.80 20016 1113696
customer order: creation 183.31 359 21997
customer order: refuse 15.69 344 1883
order to supplier: creation 48.80 1158 5856
order to supplier: removal 3.32 91292 398
invoice (draft): creation 48.85 3208 5862
invoice (draft): removal 49.89 732 5987
invoice accept: apply 43.23 18014 5187
invoice accept: cancel 45.60 832 5472
customer reserve: creation 3991.45 178 478974
customer reserve: removal 4545.29 52 545435
realization accept: apply 89.33 148 10719
realization accept: cancel 79.18 106 9502
payment from customer: creation 62.18 92 7461
payment from customer: removal 6.26 70 751
payment to supplier: creation 51.90 103 6228
payment to supplier: removal 3.54 100 425
service: total inventory turnovers 5.13 950 616
service: total monetary turnovers 7.02 24 842
service: aggregate perf. data 0.81 23237 97
service: refresh index statistics 0.03 259318 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2023 16:39:50 to 10-03-2023 16:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 417
TEST_TIME 2 1565
TEST_TIME 3 2446
TEST_TIME 4 3796
TEST_TIME 5 4215
TEST_TIME 6 4500
TEST_TIME 7 4788
TEST_TIME 8 4912
TEST_TIME 9 4994
TEST_TIME 10 5164
TEST_TIME 11 5360
TEST_TIME 12 5566
TEST_TIME 13 6141
TEST_TIME 14 6489
TEST_TIME 15 7039
TEST_TIME 16 6954
TEST_TIME 17 6965
TEST_TIME 18 7211
TEST_TIME 19 7537
TEST_TIME 20 7811
TEST_TIME 21 8341
TEST_TIME 22 8652
TEST_TIME 23 8852
TEST_TIME 24 9377
TEST_TIME 25 9357
TEST_TIME 26 9749
TEST_TIME 27 10287
TEST_TIME 28 10393
TEST_TIME 29 10375
TEST_TIME 30 10392
TEST_TIME 31 10178
TEST_TIME 32 10099
TEST_TIME 33 9983
TEST_TIME 34 9785
TEST_TIME 35 9949
TEST_TIME 36 9737
TEST_TIME 37 9723
TEST_TIME 38 9695
TEST_TIME 39 9787
TEST_TIME 40 9761
TEST_TIME 41 9775
TEST_TIME 42 9682
TEST_TIME 43 9599
TEST_TIME 44 9425
TEST_TIME 45 9404
TEST_TIME 46 9426
TEST_TIME 47 9414
TEST_TIME 48 9441
TEST_TIME 49 9286
TEST_TIME 50 9174
TEST_TIME 51 9270
TEST_TIME 52 9246
TEST_TIME 53 9240
TEST_TIME 54 9265
TEST_TIME 55 9299
TEST_TIME 56 9354
TEST_TIME 57 9421
TEST_TIME 58 9453
TEST_TIME 59 9496
TEST_TIME 60 9450
TEST_TIME 61 9566
TEST_TIME 62 9499
TEST_TIME 63 9594
TEST_TIME 64 9612
TEST_TIME 65 9705
TEST_TIME 66 9610
TEST_TIME 67 9490
TEST_TIME 68 9526
TEST_TIME 69 9460
TEST_TIME 70 9483
TEST_TIME 71 9470
TEST_TIME 72 9401
TEST_TIME 73 9326
TEST_TIME 74 9323
TEST_TIME 75 9285
TEST_TIME 76 9389
TEST_TIME 77 9395
TEST_TIME 78 9359
TEST_TIME 79 9369
TEST_TIME 80 9438
TEST_TIME 81 9403
TEST_TIME 82 9436
TEST_TIME 83 9414
TEST_TIME 84 9414
TEST_TIME 85 9402
TEST_TIME 86 9524
TEST_TIME 87 9499
TEST_TIME 88 9586
TEST_TIME 89 9490
TEST_TIME 90 9455
TEST_TIME 91 9424
TEST_TIME 92 9389
TEST_TIME 93 9380
TEST_TIME 94 9379
TEST_TIME 95 9346
TEST_TIME 96 9332
TEST_TIME 97 9342
TEST_TIME 98 9338
TEST_TIME 99 9308
TEST_TIME 100 9380
TEST_TIME 101 9388
TEST_TIME 102 9381
TEST_TIME 103 9374
TEST_TIME 104 9469
TEST_TIME 105 9489
TEST_TIME 106 9459
TEST_TIME 107 9440
TEST_TIME 108 9422
TEST_TIME 109 9368
TEST_TIME 110 9343
TEST_TIME 111 9341
TEST_TIME 112 9305
TEST_TIME 113 9267
TEST_TIME 114 9264
TEST_TIME 115 9228
TEST_TIME 116 9243
TEST_TIME 117 9304
TEST_TIME 118 9273
TEST_TIME 119 9296
TEST_TIME 120 9281

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-03-2023 16:39:54 to 10-03-2023 16:39:57.

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 1557370 1557370 0 0.00 0 23430 6 0 0
doc_list_biud 1557369 1557369 0 0.00 0 34755 24 0 0
sp_add_invoice_to_stock 5346 5187 159 2.97 33 184779 18015 0 159
sp_cancel_adding_invoice 5472 5472 0 0.00 5 57588 833 0 0
sp_cancel_client_order 1883 1883 0 0.00 4 18591 345 0 0
sp_cancel_customer_reserve 545435 545435 0 0.00 0 25428 52 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 2 3582 71 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 5840 101 0 0
sp_cancel_supplier_invoice 6000 5987 13 0.21 2 26292 732 0 13
sp_cancel_supplier_order 398 398 0 0.00 6 980938 91292 0 0
sp_cancel_write_off 9502 9502 0 0.00 1 14522 107 0 0
sp_client_order 21997 21997 0 0.00 3 12351 359 0 0
sp_customer_reserve 478974 478974 0 0.00 0 66784 179 0 0
sp_fill_shopping_cart 33705 33705 0 0.00 1 26882 41 0 0
sp_fill_shopping_cart_clo_res 478972 478972 0 0.00 0 21101 13 0 0
sp_get_clo_for_invoice 5183 5183 0 0.00 0 29626 317 0 0
sp_kill_cost_storno 16150 16150 0 0.00 0 17503 49 0 0
sp_kill_qstorno_ret_qs2qd 1106757 1106757 0 0.00 0 26257 28 0 0
sp_kill_qty_storno 584577 584577 0 0.00 0 34754 63 0 0
sp_lock_selected_doc 22047 22047 0 0.00 0 7859 6 0 0
sp_make_cost_storno 29589 29589 0 0.00 0 13215 45 0 0
sp_multiply_rows_for_pdistr 29589 29589 0 0.00 0 7911 24 0 0
sp_multiply_rows_for_qdistr 21997 21997 0 0.00 0 10932 167 0 0
sp_pay_from_customer 7461 7461 0 0.00 4 8694 93 0 0
sp_pay_to_supplier 6228 6228 0 0.00 4 12666 104 0 0
sp_payment_common 13689 13689 0 0.00 2 7715 22 0 0
sp_qd_handle_on_cancel_clo 1883 1883 0 0.00 0 18585 314 0 0
sp_qd_handle_on_invoice_upd_sts 10653 10653 0 0.00 0 34753 344 0 0
sp_qd_handle_on_reserve_upd_sts 10719 10719 0 0.00 0 14737 47 0 0
sp_reserve_write_off 10719 10719 0 0.00 6 14783 149 0 0
sp_supplier_invoice 5862 5862 0 0.00 5 109869 3209 0 0
sp_supplier_order 5856 5856 0 0.00 5 43493 1159 0 0
srv_aggregate_perf_data 98 97 1 1.02 2440 413746 23238 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1651 103711 42196 0 0
srv_find_qd_qs_mism 1063545 1063545 0 0.00 0 23429 6 0 0
srv_make_invnt_saldo 616 616 0 0.00 34 29084 951 0 0
srv_make_money_saldo 842 842 0 0.00 1 903 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 154997 389582 259319 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5187 5187 0 0.00 0 12515 156 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 1855 82 0 0
t$perf-norm:sp_cancel_client_order 1883 1883 0 0.00 0 1607 13 0 0
t$perf-norm:sp_cancel_customer_reserve 763 763 0 0.00 0 4440 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 1934 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3678 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1867 16 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 33259 1443 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 5873 10 0 0
t$perf-norm:sp_client_order 21997 21997 0 0.00 0 6824 16 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 3074 10 0 0
t$perf-norm:sp_pay_from_customer 7461 7461 0 0.00 0 4201 9 0 0
t$perf-norm:sp_pay_to_supplier 6228 6228 0 0.00 0 6081 10 0 0
t$perf-norm:sp_reserve_write_off 10719 10719 0 0.00 0 9929 14 0 0
t$perf-norm:sp_supplier_invoice 5862 5862 0 0.00 0 5281 20 0 0
t$perf-norm:sp_supplier_order 5856 5856 0 0.00 0 3712 13 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 528 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 76 3 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 3959 27 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 2071 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10680 10362 318 2.97 0 4288 17 0 318
v_all_customers 39708 39708 0 0.00 0 141 1 0 0
v_all_suppliers 32434 32434 0 0.00 0 137 1 0 0
v_all_wares 43990 43990 0 0.00 0 123 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1290 9 0 0
v_cancel_client_order 3766 3766 0 0.00 0 4141 6 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 2088 5 0 0
v_cancel_customer_reserve 1526 1526 0 0.00 0 1567 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3827 12 0 0
v_cancel_supplier_invoice 868 842 26 2.99 0 1869 21 0 26
v_cancel_supplier_order 796 796 0 0.00 0 316 4 0 0
v_cancel_write_off 2140 2140 0 0.00 0 5822 19 0 0
v_max_id_clo_ord 5855 5855 0 0.00 0 492 1 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 22156 40 0 0
v_max_id_ord_sup 5852 5852 0 0.00 0 4712 4 0 0
v_max_non_paid_invoice 1718 1718 0 0.00 0 835 3 0 0
v_max_non_paid_realizn 4140 4140 0 0.00 0 3383 5 0 0
v_min_id_clo_ord 5855 5855 0 0.00 0 5201 9 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 7296 19 0 0
v_min_id_ord_sup 5852 5852 0 0.00 0 4988 9 0 0
v_min_non_paid_invoice 6228 6228 0 0.00 0 6352 15 0 0
v_min_non_paid_realizn 7461 7461 0 0.00 0 7702 15 0 0
v_our_firm 10924 10924 0 0.00 0 15 1 0 0
v_reserve_write_off 21438 21438 0 0.00 0 3784 5 0 0
x_lock_depdocs_on_canc_invoice 11038 11038 0 0.00 0 19477 133 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 17017 361 0 0
x_make_qstorno_1000_1200 5856 5856 0 0.00 1 43438 1088 0 0
x_make_qstorno_1200_2000 5862 5862 0 0.00 2 109858 3049 0 0
x_make_qstorno_2100_3300 478974 478974 0 0.00 0 66783 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2023 16:39:58 to 10-03-2023 16:40:02.

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
14:08:50 6 8192000000 8804144848 8817381376 8315536 0.101 4 1 2 0 8417824 59504 42784
14:09:51 24139 8192000000 9907369296 10222096384 995682736 10.837 100 87 174 0 1024422272 6309808 22429728
14:11:18 12871 8192000000 9949706816 10272317440 1034621792 11.213 100 89 178 0 1066372656 9038640 22712224
14:12:34 13678 8192000000 9968214128 10297683968 1053674096 11.396 100 92 183 0 1087149808 10447600 23028112
14:14:01 8015 8192000000 10014640992 10356998144 1101173360 11.849 100 75 147 0 1130647104 11108432 18365312
14:15:14 6908 8192000000 10069616144 10425966592 1154341808 12.350 100 72 145 0 1182631728 11377552 16912368
14:16:25 4326 8192000000 10118084320 10482880512 1199829888 12.775 100 78 156 2 1230873856 13938448 17105520
14:17:33 1739 8192000000 10148901088 10521952256 1233002976 13.082 100 88 173 2 1265746608 13398048 19345584
14:18:37 1686 8192000000 10180235536 10554343424 1261273424 13.342 100 90 180 0 1294977504 13404800 20299280
14:19:40 2414 8192000000 10196487616 10585837568 1287612544 13.582 100 78 156 0 1318351648 12508608 18230496
14:20:44 23587 8192000000 10209776720 10607423488 1305798304 13.748 100 61 118 1 1327681232 8059136 13823792
14:22:12 4108 8192000000 10197095680 10609283072 1311846752 13.803 100 37 74 0 1326914208 6309344 8758112
14:23:18 7814 8192000000 10213705360 10625806336 1322971648 13.904 100 25 48 0 1334550336 6201552 5377136
14:24:28 65600 8192000000 10219277952 10640273408 1329238352 13.960 100 23 46 3 1339600624 4907024 5455248
14:26:34 5552 8192000000 10232794368 10650894336 1337629264 14.036 100 29 58 1 1350574192 6513952 6430976
14:27:41 10956 8192000000 10223577536 10644369408 1317021392 13.850 98 18 35 0 1326223936 5580496 3622048
14:28:53 56976 8192000000 10184469296 10595950592 1272402928 13.444 96 37 74 1 1286521904 4693568 9425408
14:30:52 10145 8192000000 10171349904 10575835136 1211668336 12.885 92 26 52 2 1223056688 5644944 5743408
14:32:03 7612 8192000000 10145875152 10550583296 1243261616 13.176 98 28 54 0 1255082688 5914416 5906656
14:33:12 12933 8192000000 10161893984 10565115904 1238476624 13.132 97 22 44 0 1253328016 10027664 4823728
14:34:25 29838 8192000000 10158884176 10563272704 1236979552 13.118 97 31 62 1 1249682192 5811504 6891136
14:35:57 6393 8192000000 10187916352 10599243776 1298314704 13.680 100 19 38 0 1308118048 5552528 4250816
14:37:06 6715 8192000000 10193357584 10610835456 1306044400 13.750 100 30 60 0 1320129712 6410992 7674320
14:38:15 11159 8192000000 10212086000 10629861376 1322638048 13.901 100 12 22 0 1329860736 4904912 2317776
14:39:33 76774 8192000000 10240985808 10656112640 1336791072 14.028 100 40 80 0 1353257744 7627792 8838880
14:41:54 12591 8192000000 10279727600 10692177920 1358305312 14.222 100 61 120 0 1383698416 11760160 13632944
14:43:12 6277 8192000000 10282433568 10703122432 1372900688 14.353 100 52 102 0 1397321680 13011968 11409024
14:44:27 14386 8192000000 10299572464 10728607744 1387451696 14.483 100 39 75 0 1416130400 19336400 9342304
14:45:50 9043 8192000000 10318651232 10743148544 1399282528 14.589 100 47 92 2 1429059696 19268560 10508608
14:47:03 15090 8192000000 10312065072 10738667520 1387762528 14.486 99 51 102 0 1419902384 20204352 11935504
14:48:21 9026 8192000000 10321734208 10746318848 1399062048 14.587 100 49 97 0 1432019616 21744992 11212576
14:49:37 10186 8192000000 10339797936 10766512128 1414894576 14.727 100 53 106 0 1454436048 27470784 12070688
14:50:52 4452 8192000000 10354847168 10773221376 1409713056 14.681 100 70 141 0 1462536912 35656608 17167248
14:52:01 3980 8192000000 10339658896 10757259264 1395769312 14.557 100 69 133 1 1448186464 35872576 16544576
14:53:11 3762 8192000000 10341940224 10756878336 1395891536 14.558 100 71 142 0 1451998656 38507104 17600016
14:54:23 1715 8192000000 10348824624 10762166272 1402629328 14.618 100 70 138 1 1451580384 31954816 16996240
14:55:31 2177 8192000000 10336041424 10749235200 1387654208 14.485 100 61 122 0 1439903184 36480304 15768672
14:56:40 2271 8192000000 10353472016 10768982016 1398598224 14.583 100 78 154 0 1459194416 41758752 18837440
14:57:48 1648 8192000000 10336689296 10752827392 1381299648 14.428 99 84 166 1 1443407696 41001840 21106208
14:58:53 10889 8192000000 10329649152 10757603328 1384025488 14.453 100 46 91 1 1441112960 44021056 13066416
15:00:07 13485 8192000000 10300633360 10730405888 1363215984 14.266 100 29 58 0 1416879168 45903232 7759952
15:01:24 32576 8192000000 10290938848 10717683712 1350359920 14.151 100 30 59 0 1394535136 36067392 8107824
15:03:03 23750 8192000000 10277444512 10707533824 1352058464 14.166 100 18 36 0 1380136288 22914128 5163696
15:04:36 64146 8192000000 10246667600 10687741952 1301398976 13.708 97 10 20 0 1332196928 27735776 3062176
15:06:56 75215 8192000000 10252154432 10679582720 1308465376 13.772 97 26 53 0 1325933312 11225280 6242656
15:09:17 49248 8192000000 10277063408 10698600448 1308412080 13.772 96 43 85 1 1324930256 6640688 9877488
15:11:16 103711 8192000000 10280413184 10720145408 1340129344 14.059 98 30 60 0 1360423936 13135872 7158720
15:14:08 75817 8192000000 10278961760 10723176448 1363834480 14.272 99 25 49 0 1379649712 10229568 5585664
15:16:25 31000 8192000000 10290500912 10728554496 1360926240 14.246 99 32 62 0 1382754640 13710912 8117488
15:17:58 74707 8192000000 10280860016 10741682176 1380276080 14.419 100 14 27 1 1397981040 14616896 3088064
15:20:31 49463 8192000000 10282872608 10738774016 1378787936 14.406 99 7 14 0 1389402672 9197552 1417184
15:22:20 75917 8192000000 10293390528 10747432960 1380219200 14.419 99 28 56 0 1400266800 13689104 6358496
15:24:48 17101 8192000000 10300810336 10754043904 1400707728 14.601 100 32 62 0 1416410272 8397792 7304752
15:26:08 46242 8192000000 10304994720 10766729216 1390225488 14.508 99 12 22 1 1404797776 12163552 2408736
15:28:04 78037 8192000000 10291358800 10765099008 1401758384 14.611 100 13 26 0 1418052720 13417792 2876544
15:30:22 40523 8192000000 10297175344 10758426624 1385640016 14.467 99 11 22 0 1394855056 6644400 2570640
15:32:03 59904 8192000000 10304320640 10764902400 1401750688 14.611 100 22 44 0 1416439920 9769312 4919920
15:34:03 22886 8192000000 10289811392 10741493760 1355963664 14.201 97 13 26 0 1367789680 8663680 3162336
15:35:27 43839 8192000000 10292868144 10743566336 1323171328 13.905 95 21 41 1 1337987824 9987232 4829264
15:37:14 56695 8192000000 10278397152 10714075136 1280636512 13.519 93 26 52 1 1297434336 11149648 5648176
15:39:11 50122 8192000000 10269114640 10717700096 1321789584 13.893 96 23 46 0 1338049472 11284976 4974912
15:41:02 53275 8192000000 10287282368 10743787520 1359130000 14.230 99 31 62 0 1394994304 28741760 7122544
15:43:01 63058 8192000000 10279932304 10737147904 1314222640 13.824 95 23 45 0 1336986144 17244416 5519088
15:45:14 78281 8192000000 10280310560 10729684992 1357180544 14.212 99 31 62 0 1376201456 11786112 7234800
15:47:39 52581 8192000000 10260241312 10714640384 1345741520 14.109 98 19 38 0 1364686976 14758720 4186736
15:49:35 59375 8192000000 10265235088 10718044160 1303579504 13.728 95 8 17 0 1317042016 11788176 1674336
15:51:42 55918 8192000000 10260813984 10717655040 1353290304 14.177 99 15 30 0 1372734288 15833280 3610704
15:54:06 44640 8192000000 10239533680 10691411968 1323246640 13.906 98 6 12 0 1339578448 14925056 1406752
15:56:09 31347 8192000000 10260510176 10702721024 1351998672 14.165 100 38 76 0 1370209648 9224224 8986752
15:57:45 33586 8192000000 10268522608 10710589440 1351627968 14.162 99 20 40 0 1367573808 11289056 4656784
15:59:19 30147 8192000000 10265169840 10698620928 1305303504 13.743 96 24 48 0 1319151840 8593712 5254624
16:00:50 59595 8192000000 10288224032 10725703680 1353170048 14.176 99 20 40 0 1367197440 9190576 4836816
16:02:53 31107 8192000000 10286858288 10731814912 1362374512 14.259 99 21 41 0 1383306048 16411376 4520160
16:04:25 74222 8192000000 10278984992 10717986816 1335057584 14.013 97 15 30 0 1349973600 11800768 3115248
16:06:44 91028 8192000000 10279910304 10730487808 1372566448 14.350 100 8 16 0 1394521648 19677824 2277376
16:09:23 48922 8192000000 10286408368 10741239808 1376125184 14.382 99 18 37 0 1388647456 8200976 4321296
16:11:24 46910 8192000000 10281129344 10747019264 1383203552 14.445 100 7 14 0 1393100496 8367856 1529088
16:13:25 23213 8192000000 10285004640 10735120384 1379073744 14.408 99 33 65 1 1393183008 6938784 7170480
16:14:52 28568 8192000000 10304025280 10748551168 1374524352 14.368 98 41 80 0 1390774816 7772768 8477696
16:16:24 65766 8192000000 10298117696 10748096512 1364262640 14.276 98 21 42 0 1378570800 9511584 4796576
16:18:32 68973 8192000000 10281627776 10742542336 1358332640 14.222 98 14 26 1 1374361008 12798224 3230144
16:20:42 41100 8192000000 10306268256 10751242240 1374296320 14.366 99 36 73 0 1394774480 12259632 8218528
16:22:24 65362 8192000000 10288573520 10744115200 1353667392 14.180 98 11 21 0 1370040720 14321232 2052096
16:24:37 21710 8192000000 10272115808 10700144640 1309326096 13.780 96 33 65 0 1324281664 7547776 7407792
16:26:00 27053 8192000000 10281525616 10712981504 1343539280 14.089 99 45 88 0 1365193280 12081296 9572704
16:27:28 81140 8192000000 10272462464 10721046528 1366050416 14.292 100 32 62 1 1385530768 11622256 7858096
16:29:51 45422 8192000000 10279100416 10731827200 1356587056 14.207 99 13 27 0 1379330624 19281872 3461696
16:32:30 62879 8192000000 10278482816 10726535168 1356549472 14.206 99 14 26 0 1372896208 12804736 3542000
16:34:47 53164 8192000000 10257964016 10728144896 1345462160 14.107 98 12 24 0 1367941008 19506864 2971984
16:37:08 77769 8192000000 10266048208 10709319680 1305618112 13.746 95 21 42 0 1317263008 7571392 4073504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-03-2023 16:40:04 to 10-03-2023 16:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 159 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 10-03-2023 16:40:21 to 10-03-2023 16:40:23.

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          253985
MON$OLDEST_ACTIVE               253986
MON$OLDEST_SNAPSHOT             253986
MON$NEXT_TRANSACTION            253986
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                       2023296
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
--- end of diff output ---

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

10.03.23 16:40:24. 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
2023-03-10 16:40:26 No severe PSQL-related problems occured
10.03.23 16:40:28. End of report.