Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.04.2022 19: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 16690996 kB
MemAvailable 31572984 kB
Buffers 0 kB
Cached 15055980 kB
SwapCached 3220 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.10
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-04-29 19:38:14
DTS_END                         2022-04-29 19:38:14.7050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57368
TRN_ID                          239636
ATT_ID                          716
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: 9197.58 22628 1103710
customer order: creation 170.08 349 20409
customer order: refuse 14.88 328 1785
order to supplier: creation 44.68 1247 5361
order to supplier: removal 3.50 93637 420
invoice (draft): creation 45.19 3311 5423
invoice (draft): removal 53.54 786 6425
invoice accept: apply 41.14 17827 4937
invoice accept: cancel 48.60 754 5832
customer reserve: creation 3764.33 177 451719
customer reserve: removal 4716.74 54 566009
realization accept: apply 82.94 170 9953
realization accept: cancel 85.38 100 10245
payment from customer: creation 57.81 140 6937
payment from customer: removal 5.54 88 665
payment to supplier: creation 48.52 156 5822
payment to supplier: removal 3.53 94 423
service: total inventory turnovers 4.43 1218 532
service: total monetary turnovers 6.01 24 721
service: aggregate perf. data 0.73 23177 88
service: refresh index statistics 0.03 308924 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-04-2022 19:39:49 to 29-04-2022 19:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3173
TEST_TIME 2 3633
TEST_TIME 3 4764
TEST_TIME 4 5284
TEST_TIME 5 5728
TEST_TIME 6 5389
TEST_TIME 7 5405
TEST_TIME 8 5400
TEST_TIME 9 5667
TEST_TIME 10 6485
TEST_TIME 11 6613
TEST_TIME 12 6598
TEST_TIME 13 7347
TEST_TIME 14 7321
TEST_TIME 15 7682
TEST_TIME 16 7720
TEST_TIME 17 8040
TEST_TIME 18 8316
TEST_TIME 19 8479
TEST_TIME 20 9036
TEST_TIME 21 9364
TEST_TIME 22 9321
TEST_TIME 23 9761
TEST_TIME 24 9986
TEST_TIME 25 10346
TEST_TIME 26 10615
TEST_TIME 27 11268
TEST_TIME 28 11277
TEST_TIME 29 10903
TEST_TIME 30 10754
TEST_TIME 31 10980
TEST_TIME 32 10778
TEST_TIME 33 10623
TEST_TIME 34 10590
TEST_TIME 35 10551
TEST_TIME 36 10489
TEST_TIME 37 10343
TEST_TIME 38 10264
TEST_TIME 39 10102
TEST_TIME 40 10141
TEST_TIME 41 10048
TEST_TIME 42 9892
TEST_TIME 43 9803
TEST_TIME 44 10021
TEST_TIME 45 9911
TEST_TIME 46 9750
TEST_TIME 47 9811
TEST_TIME 48 9726
TEST_TIME 49 9610
TEST_TIME 50 9629
TEST_TIME 51 9493
TEST_TIME 52 9441
TEST_TIME 53 9365
TEST_TIME 54 9259
TEST_TIME 55 9333
TEST_TIME 56 9255
TEST_TIME 57 9263
TEST_TIME 58 9200
TEST_TIME 59 9228
TEST_TIME 60 9440
TEST_TIME 61 9536
TEST_TIME 62 9614
TEST_TIME 63 9666
TEST_TIME 64 9685
TEST_TIME 65 9739
TEST_TIME 66 9833
TEST_TIME 67 9787
TEST_TIME 68 9777
TEST_TIME 69 9750
TEST_TIME 70 9683
TEST_TIME 71 9594
TEST_TIME 72 9660
TEST_TIME 73 9636
TEST_TIME 74 9630
TEST_TIME 75 9595
TEST_TIME 76 9548
TEST_TIME 77 9480
TEST_TIME 78 9483
TEST_TIME 79 9430
TEST_TIME 80 9393
TEST_TIME 81 9347
TEST_TIME 82 9410
TEST_TIME 83 9408
TEST_TIME 84 9464
TEST_TIME 85 9472
TEST_TIME 86 9486
TEST_TIME 87 9480
TEST_TIME 88 9574
TEST_TIME 89 9573
TEST_TIME 90 9611
TEST_TIME 91 9638
TEST_TIME 92 9666
TEST_TIME 93 9587
TEST_TIME 94 9531
TEST_TIME 95 9538
TEST_TIME 96 9508
TEST_TIME 97 9473
TEST_TIME 98 9443
TEST_TIME 99 9459
TEST_TIME 100 9408
TEST_TIME 101 9423
TEST_TIME 102 9475
TEST_TIME 103 9486
TEST_TIME 104 9467
TEST_TIME 105 9497
TEST_TIME 106 9512
TEST_TIME 107 9501
TEST_TIME 108 9533
TEST_TIME 109 9521
TEST_TIME 110 9515
TEST_TIME 111 9437
TEST_TIME 112 9429
TEST_TIME 113 9375
TEST_TIME 114 9389
TEST_TIME 115 9364
TEST_TIME 116 9339
TEST_TIME 117 9276
TEST_TIME 118 9280
TEST_TIME 119 9271
TEST_TIME 120 9201

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-04-2022 19:39:53 to 29-04-2022 19:39:56.

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 1522997 1522997 0 0.00 0 30046 6 0 0
doc_list_biud 1522995 1522995 0 0.00 0 65727 26 0 0
sp_add_invoice_to_stock 5022 4937 85 1.69 38 261402 17827 0 85
sp_cancel_adding_invoice 5832 5832 0 0.00 5 64277 755 0 0
sp_cancel_client_order 1785 1785 0 0.00 4 6607 328 0 0
sp_cancel_customer_reserve 566009 566009 0 0.00 1 65729 55 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 6908 88 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 3088 94 0 0
sp_cancel_supplier_invoice 6426 6425 1 0.01 2 29023 787 0 1
sp_cancel_supplier_order 420 420 0 0.00 7 1079832 93638 0 0
sp_cancel_write_off 10245 10245 0 0.00 1 11750 100 0 0
sp_client_order 20409 20409 0 0.00 3 21068 350 0 0
sp_customer_reserve 451719 451719 0 0.00 0 44010 178 0 0
sp_fill_shopping_cart 31191 31191 0 0.00 1 21046 40 0 0
sp_fill_shopping_cart_clo_res 451717 451717 0 0.00 0 15599 14 0 0
sp_get_clo_for_invoice 4933 4933 0 0.00 0 24667 354 0 0
sp_kill_cost_storno 17165 17165 0 0.00 0 20046 55 0 0
sp_kill_qstorno_ret_qs2qd 1149108 1149108 0 0.00 0 65138 30 0 0
sp_kill_qty_storno 605601 605601 0 0.00 0 65726 65 0 0
sp_lock_selected_doc 20618 20618 0 0.00 0 7021 4 0 0
sp_make_cost_storno 27645 27645 0 0.00 0 30035 53 0 0
sp_multiply_rows_for_pdistr 27644 27644 0 0.00 0 6287 31 0 0
sp_multiply_rows_for_qdistr 20409 20409 0 0.00 0 21050 160 0 0
sp_pay_from_customer 6937 6937 0 0.00 4 29216 141 0 0
sp_pay_to_supplier 5822 5822 0 0.00 5 31698 157 0 0
sp_payment_common 12758 12758 0 0.00 2 31612 42 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 6160 290 0 0
sp_qd_handle_on_invoice_upd_sts 10765 10765 0 0.00 0 64838 316 0 0
sp_qd_handle_on_reserve_upd_sts 9952 9952 0 0.00 0 9240 50 0 0
sp_reserve_write_off 9953 9953 0 0.00 6 12937 171 0 0
sp_supplier_invoice 5423 5423 0 0.00 7 94509 3311 0 0
sp_supplier_order 5361 5361 0 0.00 5 27019 1247 0 0
srv_aggregate_perf_data 89 88 1 1.12 2940 391325 23177 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1628 134307 44577 0 0
srv_find_qd_qs_mism 1057376 1057376 0 0.00 0 10242 5 0 0
srv_make_invnt_saldo 532 532 0 0.00 34 113222 1218 0 0
srv_make_money_saldo 721 721 0 0.00 1 1064 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 188969 397279 308924 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 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 4937 4937 0 0.00 0 15973 160 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 2741 70 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 7807 19 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1457 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 2297 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 496 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 3288 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 21833 1380 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 823 5 0 0
t$perf-norm:sp_client_order 20409 20409 0 0.00 0 8219 15 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 3457 10 0 0
t$perf-norm:sp_pay_from_customer 6937 6937 0 0.00 0 8363 10 0 0
t$perf-norm:sp_pay_to_supplier 5822 5822 0 0.00 0 5654 9 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 8268 11 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 16924 20 0 0
t$perf-norm:sp_supplier_order 5361 5361 0 0.00 0 3910 18 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 1606 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 722 12 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 6221 33 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 3330 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10032 9862 170 1.69 0 7853 19 0 170
v_all_customers 37122 37122 0 0.00 0 17 1 0 0
v_all_suppliers 29878 29878 0 0.00 0 18 1 0 0
v_all_wares 40818 40818 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 3631 17 0 0
v_cancel_client_order 3570 3570 0 0.00 0 4220 8 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 1991 8 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 2760 6 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 2677 13 0 0
v_cancel_supplier_invoice 784 782 2 0.25 0 543 12 0 2
v_cancel_supplier_order 840 840 0 0.00 0 581 4 0 0
v_cancel_write_off 1960 1960 0 0.00 0 7170 23 0 0
v_max_id_clo_ord 5360 5360 0 0.00 0 311 1 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 4470 20 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 4152 5 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 2462 6 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 6569 6 0 0
v_min_id_clo_ord 5360 5360 0 0.00 0 3520 7 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 4482 14 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 4278 5 0 0
v_min_non_paid_invoice 5822 5822 0 0.00 0 31599 29 0 0
v_min_non_paid_realizn 6935 6935 0 0.00 0 29129 34 0 0
v_our_firm 10172 10172 0 0.00 0 13 1 0 0
v_reserve_write_off 19904 19904 0 0.00 0 7321 11 0 0
x_lock_depdocs_on_canc_invoice 11866 11866 0 0.00 0 13551 125 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 10231 423 0 0
x_make_qstorno_1000_1200 5361 5361 0 0.00 1 26931 1168 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 3 86462 3165 0 0
x_make_qstorno_2100_3300 451719 451719 0 0.00 0 43706 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-04-2022 19:39:57 to 29-04-2022 19:40:01.

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
17:08:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:19 14473 8192000000 9888766784 10194915328 973329104 10.619 100 89 178 1 1002880080 5737728 23813248
17:10:39 21034 8192000000 9936403152 10256318464 1017116944 11.044 100 80 160 0 1045771552 7734176 20920432
17:12:10 17377 8192000000 9984296688 10320699392 1068457760 11.537 100 79 158 0 1097689440 9565920 19665760
17:13:29 10152 8192000000 10023508864 10372214784 1113053760 11.961 100 78 154 2 1143444368 11580672 18809936
17:14:43 6338 8192000000 10081990416 10437398528 1168318176 12.481 100 75 151 0 1197925040 12775296 16831568
17:15:56 5985 8192000000 10131902288 10496372736 1211562224 12.884 100 80 161 0 1242647680 13088528 17996928
17:17:05 3481 8192000000 10153551616 10527875072 1242962912 13.174 100 88 177 1 1277970640 15721504 19286224
17:18:10 1642 8192000000 10201133984 10579599360 1282728592 13.538 100 94 185 3 1316246688 12901776 20616320
17:19:12 34713 8192000000 10200260288 10591219712 1294817040 13.648 100 65 127 1 1317805488 8276208 14712240
17:20:48 6278 8192000000 10190579792 10596212736 1299841184 13.694 100 29 58 0 1312726432 6377872 6507376
17:21:57 7969 8192000000 10190242000 10608828416 1313139456 13.815 100 19 38 1 1323490768 6134416 4216896
17:23:10 10801 8192000000 10202077568 10620030976 1317549984 13.855 100 11 22 1 1325805856 5509632 2746240
17:24:23 53429 8192000000 10245097920 10646396928 1326769184 13.938 100 58 117 1 1346762064 5023712 14969168
17:26:16 2878 8192000000 10232467680 10640822272 1327872640 13.948 100 45 90 2 1344633232 5539488 11221104
17:27:20 3858 8192000000 10208102976 10619523072 1315021568 13.832 100 19 38 1 1325469104 5876032 4571504
17:28:28 50874 8192000000 10155899184 10567032832 1184530928 12.632 91 20 40 1 1194270128 5103184 4636016
17:30:23 6127 8192000000 10148561280 10548133888 1236599728 13.115 98 36 72 1 1250714320 5452992 8661600
17:31:29 6425 8192000000 10139002320 10534494208 1216377360 12.928 98 30 60 0 1233448896 9791392 7280144
17:32:40 50143 8192000000 10176803120 10561384448 1259075328 13.322 99 60 119 0 1278919152 5408192 14435632
17:34:31 5176 8192000000 10180642448 10586832896 1283990432 13.549 100 30 60 0 1297592336 6548160 7053744
17:35:37 7623 8192000000 10197087712 10608312320 1301602464 13.710 100 22 46 2 1311418752 4780832 5035456
17:36:46 7136 8192000000 10196821632 10615664640 1307853600 13.767 100 16 32 2 1316668384 4588528 4226256
17:37:57 16567 8192000000 10192238944 10611941376 1303329504 13.726 99 37 75 0 1317896064 5798352 8768208
17:39:23 17779 8192000000 10245307456 10656989184 1339882096 14.056 100 36 67 1 1354721200 7493648 7345456
17:40:52 23789 8192000000 10268874048 10686717952 1362786032 14.262 100 36 72 2 1383064992 11843920 8435040
17:42:18 9412 8192000000 10291344240 10708967424 1377489792 14.394 100 49 96 3 1401107952 12356448 11261712
17:43:33 7215 8192000000 10302657168 10723192832 1388856288 14.496 100 50 99 1 1415989328 14485952 12647088
17:44:51 7239 8192000000 10310069168 10729684992 1382363968 14.438 99 54 108 1 1411946256 15194080 14388208
17:46:03 8770 8192000000 10326411888 10752638976 1406232272 14.650 100 52 104 0 1437640064 19385216 12022576
17:47:14 3344 8192000000 10350465472 10771464192 1417678880 14.752 100 72 142 0 1459105040 24259248 17166912
17:48:25 7160 8192000000 10356020144 10776944640 1420362576 14.776 100 68 136 1 1465896160 28803456 16730128
17:49:35 12820 8192000000 10347340464 10767785984 1411239760 14.695 100 63 127 3 1450931648 24939744 14752144
17:50:54 2367 8192000000 10356082240 10775789568 1412497712 14.706 100 68 136 1 1462508832 32748880 17262240
17:52:01 1624 8192000000 10321875680 10727628800 1365265392 14.285 99 81 160 3 1417286128 31054688 20966048
17:53:13 7634 8192000000 10363170064 10768011264 1403521824 14.626 100 73 146 0 1457973424 36288544 18163056
17:54:28 3111 8192000000 10340335840 10751397888 1391281808 14.517 100 65 130 2 1444510368 36894736 16333824
17:55:39 17763 8192000000 10327348784 10749939712 1386510448 14.475 100 40 80 0 1437840624 40296752 11033424
17:57:02 19563 8192000000 10329534288 10760908800 1368688976 14.315 98 39 78 0 1419794512 40093088 11012448
17:58:24 19206 8192000000 10302551712 10736193536 1364801200 14.280 99 31 62 1 1411403344 38163056 8439088
17:59:59 23310 8192000000 10308231040 10740101120 1357967504 14.219 99 25 50 1 1405371872 40408656 6995712
18:01:25 26088 8192000000 10305866256 10744041472 1362041888 14.256 100 32 64 0 1413934272 43166464 8725920
18:02:54 34760 8192000000 10284069152 10726850560 1355121872 14.194 99 18 36 3 1398769024 38419728 5227424
18:04:34 28344 8192000000 10296391792 10732974080 1344463216 14.098 99 18 36 1 1388249120 38206256 5579648
18:06:09 97450 8192000000 10287671472 10741415936 1360593408 14.243 100 21 42 0 1388647808 22687136 5367264
18:09:04 64118 8192000000 10267084560 10716016640 1344720224 14.100 98 16 32 0 1361014288 13052752 3241312
18:11:24 45611 8192000000 10281017088 10731491328 1367056672 14.301 99 38 76 0 1388435936 12766720 8612544
18:14:01 27282 8192000000 10268251424 10711433216 1334501936 14.008 98 10 20 0 1350863440 14099552 2261952
18:15:33 32057 8192000000 10267356432 10716672000 1367775264 14.307 100 12 24 0 1378724944 8011744 2937936
18:17:16 26447 8192000000 10290311824 10737831936 1376300528 14.383 100 13 26 1 1392778048 13416688 3060832
18:18:49 63838 8192000000 10293317360 10748719104 1383164560 14.445 100 18 36 0 1401031760 13139424 4727776
18:21:12 77373 8192000000 10286577536 10755514368 1374546784 14.368 99 21 43 0 1400234976 20117968 5570224
18:23:32 27991 8192000000 10283714816 10750525440 1387938912 14.487 100 9 18 1 1402842784 12963024 1940848
18:25:01 68418 8192000000 10281081872 10745634816 1355645952 14.198 97 29 58 0 1375520112 13505328 6368832
18:27:13 84698 8192000000 10274463776 10743054336 1370150656 14.328 99 9 18 1 1386288432 13870896 2266880
18:29:38 24602 8192000000 10274338144 10719264768 1358551552 14.224 99 50 98 1 1382673008 13070064 11051392
18:31:03 65807 8192000000 10273311088 10735558656 1373345568 14.357 100 23 46 2 1388396736 8823984 6227184
18:33:14 66085 8192000000 10276795712 10754002944 1371681024 14.342 99 8 16 1 1394340480 20576944 2082512
18:35:25 46469 8192000000 10304057872 10763595776 1384181424 14.454 99 20 40 0 1406355872 17070736 5103712
18:37:11 60645 8192000000 10306462064 10760806400 1338078928 14.040 96 23 46 2 1365679120 22141856 5458336
18:39:13 65279 8192000000 10272990560 10743730176 1317451120 13.854 95 10 20 1 1340581888 21086880 2043888
18:41:18 47278 8192000000 10291045968 10750861312 1362995840 14.264 98 9 18 0 1381322560 16235728 2090992
18:43:07 134306 8192000000 10283858624 10738266112 1319008480 13.868 95 13 26 0 1341106048 19382960 2714608
18:46:21 93556 8192000000 10270305632 10719055872 1315610896 13.837 96 32 64 0 1340760832 17863216 7286720
18:49:32 39431 8192000000 10285929360 10732437504 1368896304 14.317 100 39 78 0 1398498992 20907216 8695472
18:51:58 64946 8192000000 10250461280 10710814720 1315985760 13.840 96 9 18 1 1332211600 13803552 2422288
18:54:03 77547 8192000000 10239956272 10687528960 1276433152 13.480 94 11 22 0 1288527104 10033360 2060592
18:56:58 26136 8192000000 10239539360 10682703872 1332633136 13.991 99 20 40 0 1344462928 7795136 4034656
18:58:25 74230 8192000000 10257919472 10707988480 1348265904 14.132 99 10 20 1 1359036368 8613152 2157312
19:00:47 77378 8192000000 10284435392 10734415872 1361457008 14.250 99 7 14 0 1375233600 12293776 1482816
19:03:15 45344 8192000000 10294756944 10751557632 1370643136 14.333 99 17 34 0 1392577952 18266608 3668208
19:05:05 26303 8192000000 10292901568 10735828992 1367332144 14.303 99 28 56 1 1382690624 9417280 5941200
19:06:38 41321 8192000000 10289928816 10735251456 1363985008 14.273 99 28 56 0 1383300960 13430816 5885136
19:08:23 72766 8192000000 10306104272 10759335936 1381352240 14.429 99 25 50 0 1403067744 16473232 5242272
19:10:48 111198 8192000000 10277740528 10730303488 1359470656 14.233 98 18 36 0 1373468368 10369712 3628000
19:13:50 28301 8192000000 10261666528 10723397632 1338087088 14.040 97 17 33 0 1350615360 8121520 4406752
19:15:18 63530 8192000000 10278861536 10740633600 1367786464 14.307 99 7 14 0 1380538592 11212272 1539856
19:17:22 69919 8192000000 10300819488 10739372032 1335721520 14.019 96 20 40 0 1353521856 13251504 4548832
19:19:43 93182 8192000000 10286304560 10739716096 1351995872 14.165 98 16 32 0 1368040240 12627920 3416448
19:22:17 33544 8192000000 10302413424 10743320576 1366455920 14.295 99 36 70 1 1387106720 12250064 8400736
19:23:54 40898 8192000000 10284263728 10728087552 1339990720 14.057 97 22 43 1 1353605808 8362752 5252336
19:25:37 74749 8192000000 10288278528 10740559872 1357608944 14.216 98 9 18 0 1376083072 15931024 2543104
19:27:52 70969 8192000000 10279271456 10736074752 1363080144 14.265 98 21 42 0 1377615472 9972928 4562400
19:30:57 25154 8192000000 10267329888 10728538112 1371004256 14.336 100 10 20 0 1383198672 9882016 2312400
19:32:23 60759 8192000000 10271027344 10733232128 1348790528 14.137 97 16 33 0 1360279584 8026880 3462176
19:34:24 58537 8192000000 10288141152 10747637760 1378961872 14.407 99 8 16 0 1389465072 8657632 1845568
19:37:41 31454 8192000000 10292197920 10734612480 1342281936 14.078 97 30 60 1 1360206160 10640912 7283312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-04-2022 19:40:03 to 29-04-2022 19:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 85 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-04-2022 19:40:18 to 29-04-2022 19:40:20.

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          236761
MON$OLDEST_ACTIVE               239761
MON$OLDEST_SNAPSHOT             239761
MON$NEXT_TRANSACTION            239761
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                       2025568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33580 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33580 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33580 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33580 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Apr 29 19:38:17 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Apr 29 19:38:20 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Apr 29 19:38:21 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Apr 29 19:38:21 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

29.04.22 19:40:21. 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-04-29 19:40:24 No severe PSQL-related problems occured
29.04.22 19:40:25. End of report.