Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2022 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 15393160 kB
MemAvailable 30560312 kB
Buffers 0 kB
Cached 16300672 kB
SwapCached 2068 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-30 16:38:29
DTS_END                         2022-09-30 16:38:29.2030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51006
TRN_ID                          247535
ATT_ID                          675
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: 9647.94 20342 1157753
customer order: creation 181.41 331 21769
customer order: refuse 15.60 333 1872
order to supplier: creation 46.66 1173 5599
order to supplier: removal 3.81 93241 457
invoice (draft): creation 46.93 2796 5631
invoice (draft): removal 55.80 793 6696
invoice accept: apply 43.01 16533 5161
invoice accept: cancel 49.63 758 5956
customer reserve: creation 4012.49 162 481499
customer reserve: removal 4880.27 57 585633
realization accept: apply 87.42 174 10490
realization accept: cancel 89.07 95 10688
payment from customer: creation 62.67 114 7520
payment from customer: removal 5.91 96 709
payment to supplier: creation 51.28 127 6153
payment to supplier: removal 3.65 75 438
service: total inventory turnovers 4.66 1259 559
service: total monetary turnovers 6.75 26 810
service: aggregate perf. data 0.91 21101 109
service: refresh index statistics 0.03 267586 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2022 16:39:25 to 30-09-2022 16:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2524
TEST_TIME 2 2230
TEST_TIME 3 2172
TEST_TIME 4 4039
TEST_TIME 5 3943
TEST_TIME 6 4361
TEST_TIME 7 5082
TEST_TIME 8 5380
TEST_TIME 9 6356
TEST_TIME 10 6340
TEST_TIME 11 6521
TEST_TIME 12 6502
TEST_TIME 13 6529
TEST_TIME 14 6560
TEST_TIME 15 6603
TEST_TIME 16 7282
TEST_TIME 17 7428
TEST_TIME 18 8213
TEST_TIME 19 8057
TEST_TIME 20 7974
TEST_TIME 21 8087
TEST_TIME 22 8036
TEST_TIME 23 8783
TEST_TIME 24 8944
TEST_TIME 25 9252
TEST_TIME 26 9214
TEST_TIME 27 9470
TEST_TIME 28 9857
TEST_TIME 29 10037
TEST_TIME 30 9962
TEST_TIME 31 10206
TEST_TIME 32 10511
TEST_TIME 33 10427
TEST_TIME 34 10411
TEST_TIME 35 10321
TEST_TIME 36 10154
TEST_TIME 37 10104
TEST_TIME 38 10187
TEST_TIME 39 10185
TEST_TIME 40 10387
TEST_TIME 41 10190
TEST_TIME 42 10171
TEST_TIME 43 10337
TEST_TIME 44 10404
TEST_TIME 45 10368
TEST_TIME 46 10308
TEST_TIME 47 10209
TEST_TIME 48 10073
TEST_TIME 49 10086
TEST_TIME 50 10030
TEST_TIME 51 10016
TEST_TIME 52 9957
TEST_TIME 53 9884
TEST_TIME 54 9867
TEST_TIME 55 9931
TEST_TIME 56 10020
TEST_TIME 57 9954
TEST_TIME 58 9925
TEST_TIME 59 9913
TEST_TIME 60 9819
TEST_TIME 61 9747
TEST_TIME 62 9739
TEST_TIME 63 9732
TEST_TIME 64 9775
TEST_TIME 65 9771
TEST_TIME 66 9862
TEST_TIME 67 9854
TEST_TIME 68 9943
TEST_TIME 69 9927
TEST_TIME 70 9902
TEST_TIME 71 9976
TEST_TIME 72 9963
TEST_TIME 73 9938
TEST_TIME 74 10028
TEST_TIME 75 10027
TEST_TIME 76 10094
TEST_TIME 77 10106
TEST_TIME 78 9982
TEST_TIME 79 9930
TEST_TIME 80 9921
TEST_TIME 81 9866
TEST_TIME 82 9898
TEST_TIME 83 9829
TEST_TIME 84 9830
TEST_TIME 85 9838
TEST_TIME 86 9802
TEST_TIME 87 9771
TEST_TIME 88 9719
TEST_TIME 89 9682
TEST_TIME 90 9669
TEST_TIME 91 9690
TEST_TIME 92 9699
TEST_TIME 93 9667
TEST_TIME 94 9689
TEST_TIME 95 9764
TEST_TIME 96 9753
TEST_TIME 97 9796
TEST_TIME 98 9774
TEST_TIME 99 9762
TEST_TIME 100 9782
TEST_TIME 101 9798
TEST_TIME 102 9845
TEST_TIME 103 9833
TEST_TIME 104 9910
TEST_TIME 105 9821
TEST_TIME 106 9786
TEST_TIME 107 9751
TEST_TIME 108 9752
TEST_TIME 109 9725
TEST_TIME 110 9710
TEST_TIME 111 9669
TEST_TIME 112 9630
TEST_TIME 113 9628
TEST_TIME 114 9639
TEST_TIME 115 9630
TEST_TIME 116 9610
TEST_TIME 117 9608
TEST_TIME 118 9569
TEST_TIME 119 9712
TEST_TIME 120 9649

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-09-2022 16:39:29 to 30-09-2022 16:39:32.

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 1604524 1604524 0 0.00 0 23900 6 0 0
doc_list_biud 1604519 1604519 0 0.00 0 34836 26 0 0
sp_add_invoice_to_stock 5234 5161 73 1.39 32 187936 16534 0 73
sp_cancel_adding_invoice 5956 5956 0 0.00 4 57648 759 0 0
sp_cancel_client_order 1872 1872 0 0.00 4 16467 334 0 0
sp_cancel_customer_reserve 585633 585633 0 0.00 0 25795 57 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 10867 97 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 4100 75 0 0
sp_cancel_supplier_invoice 6704 6696 8 0.11 1 23697 793 0 8
sp_cancel_supplier_order 457 457 0 0.00 5 1042496 93242 0 0
sp_cancel_write_off 10688 10688 0 0.00 1 22104 96 0 0
sp_client_order 21769 21769 0 0.00 3 20852 331 0 0
sp_customer_reserve 481499 481499 0 0.00 0 70288 162 0 0
sp_fill_shopping_cart 32999 32999 0 0.00 1 19978 32 0 0
sp_fill_shopping_cart_clo_res 481499 481499 0 0.00 0 20740 13 0 0
sp_get_clo_for_invoice 5160 5160 0 0.00 0 34031 289 0 0
sp_kill_cost_storno 17791 17791 0 0.00 0 11046 51 0 0
sp_kill_qstorno_ret_qs2qd 1189107 1189107 0 0.00 0 24489 31 0 0
sp_kill_qty_storno 626947 626947 0 0.00 0 34832 67 0 0
sp_lock_selected_doc 21794 21794 0 0.00 0 11299 7 0 0
sp_make_cost_storno 29323 29323 0 0.00 0 23898 56 0 0
sp_multiply_rows_for_pdistr 29320 29320 0 0.00 0 9118 24 0 0
sp_multiply_rows_for_qdistr 21769 21769 0 0.00 0 11921 140 0 0
sp_pay_from_customer 7520 7520 0 0.00 4 23907 115 0 0
sp_pay_to_supplier 6153 6153 0 0.00 4 12846 127 0 0
sp_payment_common 13673 13673 0 0.00 1 10273 26 0 0
sp_qd_handle_on_cancel_clo 1872 1872 0 0.00 0 16461 310 0 0
sp_qd_handle_on_invoice_upd_sts 11116 11116 0 0.00 0 34828 330 0 0
sp_qd_handle_on_reserve_upd_sts 10485 10485 0 0.00 0 17162 56 0 0
sp_reserve_write_off 10490 10490 0 0.00 6 17267 174 0 0
sp_supplier_invoice 5631 5631 0 0.00 5 105650 2796 0 0
sp_supplier_order 5599 5599 0 0.00 5 34885 1174 0 0
srv_aggregate_perf_data 110 109 1 0.90 2158 240788 21101 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1271 146904 44133 0 0
srv_find_qd_qs_mism 1108323 1108323 0 0.00 0 20006 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 40 92866 1260 0 0
srv_make_money_saldo 810 810 0 0.00 0 4046 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 118684 348625 267586 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 5161 5161 0 0.00 0 10250 147 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 17212 157 0 0
t$perf-norm:sp_cancel_client_order 1872 1872 0 0.00 0 3873 15 0 0
t$perf-norm:sp_cancel_customer_reserve 771 771 0 0.00 0 3750 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 1875 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 1307 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 3157 23 0 0
t$perf-norm:sp_cancel_supplier_order 457 457 0 0.00 0 27522 1444 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 4836 11 0 0
t$perf-norm:sp_client_order 21769 21769 0 0.00 0 7135 16 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 3542 11 0 0
t$perf-norm:sp_pay_from_customer 7520 7520 0 0.00 0 6900 11 0 0
t$perf-norm:sp_pay_to_supplier 6153 6153 0 0.00 0 8278 12 0 0
t$perf-norm:sp_reserve_write_off 10490 10490 0 0.00 0 11651 13 0 0
t$perf-norm:sp_supplier_invoice 5631 5631 0 0.00 0 4966 17 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 6275 16 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1329 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 701 18 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 2542 19 0 0
t$perf-norm:srv_make_money_saldo 810 810 0 0.00 0 3677 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10464 10318 146 1.39 0 7529 18 0 146
v_all_customers 39988 39988 0 0.00 0 20 1 0 0
v_all_suppliers 31750 31750 0 0.00 0 189 1 0 0
v_all_wares 43538 43538 0 0.00 0 189 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 911 7 0 0
v_cancel_client_order 3744 3744 0 0.00 0 1785 3 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 3840 9 0 0
v_cancel_customer_reserve 1542 1542 0 0.00 0 793 5 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 2583 6 0 0
v_cancel_supplier_invoice 928 912 16 1.72 0 3892 21 0 16
v_cancel_supplier_order 914 914 0 0.00 0 2397 11 0 0
v_cancel_write_off 2048 2048 0 0.00 0 3332 15 0 0
v_max_id_clo_ord 5599 5599 0 0.00 0 865 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 2705 14 0 0
v_max_id_ord_sup 5631 5631 0 0.00 0 3477 3 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 478 2 0 0
v_max_non_paid_realizn 3881 3881 0 0.00 0 4859 5 0 0
v_min_id_clo_ord 5599 5599 0 0.00 0 2164 5 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 9315 25 0 0
v_min_id_ord_sup 5631 5631 0 0.00 0 5508 6 0 0
v_min_non_paid_invoice 6153 6153 0 0.00 0 7622 14 0 0
v_min_non_paid_realizn 7520 7520 0 0.00 0 9107 18 0 0
v_our_firm 10828 10828 0 0.00 0 15 1 0 0
v_reserve_write_off 20970 20970 0 0.00 0 2638 5 0 0
x_lock_depdocs_on_canc_invoice 12196 12196 0 0.00 0 23206 103 0 0
x_lock_depdocs_on_canc_sup_ord 457 457 0 0.00 0 25075 373 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 1 34874 1104 0 0
x_make_qstorno_1200_2000 5631 5631 0 0.00 2 105600 2682 0 0
x_make_qstorno_2100_3300 481499 481499 0 0.00 0 70287 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2022 16:39:33 to 30-09-2022 16:39:37.

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:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:33 7389 8192000000 9876731632 10182844416 966555744 10.553 100 86 172 0 994957728 5402208 22999776
14:10:57 36873 8192000000 9928368832 10245320704 1011174848 10.987 100 88 176 0 1042202816 8182272 22845696
14:12:39 10227 8192000000 9955450608 10286387200 1049643792 11.357 100 79 158 0 1079100960 9372336 20084832
14:13:54 8257 8192000000 10020794224 10363801600 1112221648 11.953 100 77 153 0 1140496880 9965488 18309744
14:15:06 6360 8192000000 10080951712 10436878336 1166503808 12.464 100 82 160 0 1196595040 11925104 18166128
14:16:15 2883 8192000000 10123677680 10490830848 1206749744 12.839 100 84 166 1 1239506192 14762720 17993728
14:17:23 1784 8192000000 10146620480 10520064000 1238988512 13.137 100 81 162 0 1270225696 13551664 17685520
14:18:27 2054 8192000000 10190745888 10576343040 1280842704 13.521 100 72 144 1 1308302368 12037856 15421808
14:19:30 1259 8192000000 10210854608 10602397696 1299662992 13.692 100 85 169 2 1330723808 12378448 18682368
14:20:32 7888 8192000000 10197931968 10606387200 1308853456 13.776 100 33 66 1 1323820960 7620352 7347152
14:21:41 11633 8192000000 10200853872 10616467456 1318146160 13.860 100 19 37 1 1328116752 5879024 4091568
14:22:53 13945 8192000000 10211948960 10634661888 1325905728 13.930 100 24 48 0 1337129664 6125552 5098384
14:24:15 46072 8192000000 10216144464 10641477632 1333209232 13.996 100 41 80 0 1347619040 5344080 9065728
14:26:03 6017 8192000000 10226336032 10648485888 1339221392 14.050 100 17 34 0 1348422864 5611184 3590288
14:27:10 7709 8192000000 10223593216 10645073920 1333113440 13.995 100 23 43 2 1343751456 5345360 5292656
14:28:18 7132 8192000000 10183280256 10602917888 1281966768 13.531 98 17 34 1 1291751744 6078928 3706048
14:29:31 34210 8192000000 10189231168 10604486656 1273330464 13.452 97 29 58 0 1285719024 5409008 6979552
14:31:09 2605 8192000000 10157740560 10558492672 1262272704 13.351 100 39 76 0 1278635536 6779280 9583552
14:32:12 6788 8192000000 10115187056 10511560704 1202949088 12.804 97 19 38 0 1211882880 4801776 4132016
14:33:19 11428 8192000000 10142451696 10549075968 1227227712 13.028 97 25 51 1 1237342032 4827008 5287312
14:34:32 26069 8192000000 10160544720 10569588736 1248731168 13.227 98 26 52 1 1260751888 5901424 6119296
14:36:00 6463 8192000000 10178037792 10593132544 1289692592 13.601 100 20 40 1 1299951440 5467264 4791584
14:37:07 10702 8192000000 10198165280 10612613120 1300553376 13.700 100 16 33 0 1309724640 5017056 4154208
14:38:18 96411 8192000000 10227168880 10647060480 1325149472 13.923 100 21 40 0 1333943168 4811776 3981920
14:41:04 38857 8192000000 10265478032 10683064320 1355415552 14.196 100 43 84 1 1375431680 10381680 9634448
14:42:48 18103 8192000000 10268832880 10695811072 1367569408 14.305 100 37 71 1 1387722240 11164400 8988432
14:44:12 11362 8192000000 10296163648 10717372416 1377965984 14.398 100 50 100 1 1407274016 17417600 11890432
14:45:29 10016 8192000000 10299591456 10726510592 1383021440 14.444 100 41 82 0 1412832176 18829168 10981568
14:46:41 7593 8192000000 10319675328 10739396608 1391206304 14.517 100 65 127 0 1428503712 22029488 15267920
14:47:53 1864 8192000000 10324722720 10748768256 1399205792 14.588 100 64 127 1 1436276944 22124240 14946912
14:48:59 9008 8192000000 10326183632 10748743680 1393328560 14.536 100 64 126 1 1435509760 27672944 14508256
14:50:14 3921 8192000000 10343537248 10763583488 1394914656 14.550 99 79 157 0 1443305536 30004608 18386272
14:51:23 5109 8192000000 10358839872 10773934080 1406366624 14.652 99 72 144 1 1457460624 34409328 16684672
14:52:31 1270 8192000000 10351038304 10765082624 1396468736 14.564 99 79 157 2 1455708624 38740736 20499152
14:53:38 7627 8192000000 10364395632 10784133120 1408343136 14.669 100 54 108 0 1469344416 46746624 14254656
14:54:49 8655 8192000000 10359087264 10776248320 1399183104 14.588 100 70 141 1 1463275792 45980512 18112176
14:56:03 3012 8192000000 10333745072 10749558784 1381954992 14.434 100 62 121 0 1437981216 40714704 15311520
14:57:10 11442 8192000000 10319950192 10745483264 1383369600 14.447 100 48 96 0 1427333712 31413024 12551088
14:58:34 5728 8192000000 10327337184 10740563968 1352730384 14.172 97 57 112 0 1402870912 34935856 15204672
14:59:45 2440 8192000000 10342947104 10752237568 1380202784 14.418 100 81 159 0 1440042304 39138288 20701232
15:00:54 6050 8192000000 10341131472 10755825664 1375314000 14.375 100 70 139 1 1438145696 44664336 18167360
15:02:10 11846 8192000000 10317958352 10745495552 1366037984 14.292 99 35 70 1 1414157408 38268080 9851344
15:03:28 13998 8192000000 10299521696 10735800320 1352389376 14.169 98 35 68 1 1391408048 30487824 8530848
15:04:46 17057 8192000000 10328549152 10755190784 1371470960 14.340 99 30 57 0 1424598752 44880944 8246848
15:06:07 15226 8192000000 10320496368 10750136320 1350758752 14.154 98 29 58 0 1405816128 46824880 8232496
15:07:41 42711 8192000000 10305103184 10732646400 1364174656 14.275 100 51 101 0 1405001856 29517760 11309440
15:09:26 32611 8192000000 10297189680 10742988800 1359015792 14.229 99 16 32 0 1394151216 31139568 3995856
15:11:14 146195 8192000000 10281696880 10737184768 1326949232 13.940 96 7 14 0 1338142128 10078160 1114736
15:14:42 118186 8192000000 10313786480 10754310144 1393330992 14.536 100 18 37 1 1417893088 19914320 4647776
15:17:40 70133 8192000000 10311019184 10780319744 1397194512 14.570 100 7 14 0 1419982176 21332224 1455440
15:19:53 37671 8192000000 10311400112 10761674752 1391638736 14.520 99 36 71 0 1414301824 13683296 8979792
15:21:31 146904 8192000000 10308436240 10771521536 1388120368 14.489 99 3 6 0 1404220048 15552736 546944
15:25:02 112410 8192000000 10302934384 10750099456 1368409200 14.313 97 29 58 0 1385500192 10775360 6315632
15:28:15 85782 8192000000 10305216288 10763210752 1386816976 14.477 99 28 54 1 1405457840 12501952 6138912
15:30:47 13478 8192000000 10315137744 10781655040 1407863904 14.665 100 11 22 0 1425545984 15099104 2582976
15:32:01 20339 8192000000 10286151408 10725421056 1350990688 14.156 96 39 78 0 1372219152 11901072 9327392
15:33:26 33136 8192000000 10303566208 10756587520 1392896592 14.532 100 42 87 0 1421322432 18378256 10047584
15:35:00 59374 8192000000 10294706480 10756849664 1385401536 14.465 99 11 22 0 1395404608 7639856 2363216
15:37:00 22394 8192000000 10277049616 10743046144 1366314320 14.294 98 6 13 0 1377014528 9320736 1379472
15:38:27 67137 8192000000 10278259472 10744737792 1360404880 14.241 98 9 18 0 1370691968 8298256 1988832
15:41:20 68468 8192000000 10294940944 10770477056 1391108336 14.516 100 11 22 0 1404861744 11693584 2059824
15:43:28 54711 8192000000 10295108448 10752868352 1344380672 14.097 96 12 24 0 1359811072 12666640 2763760
15:45:24 45565 8192000000 10281686752 10744090624 1359541472 14.233 98 32 62 0 1379304800 12873424 6889904
15:47:10 21085 8192000000 10279998160 10726744064 1367342720 14.303 100 51 102 0 1388599296 10215744 11040832
15:48:52 10892 8192000000 10272372048 10711887872 1345209664 14.104 99 45 90 0 1370009232 15052944 9746624
15:50:04 79050 8192000000 10281311024 10727940096 1319845360 13.875 96 18 36 0 1337061888 13146176 4070352
15:52:24 43538 8192000000 10278384816 10710695936 1301248896 13.707 95 38 76 2 1319023776 9431456 8343424
15:54:13 89759 8192000000 10264283568 10717413376 1310665696 13.792 97 11 20 0 1333814416 20811600 2337120
15:58:12 67163 8192000000 10273877856 10719961088 1350961168 14.156 99 29 58 0 1373132128 15640832 6530128
16:00:23 49310 8192000000 10260865872 10720256000 1339612064 14.054 98 7 14 0 1353798128 12578656 1607408
16:02:13 42916 8192000000 10258214192 10720239616 1361504624 14.251 100 6 12 0 1372673008 9534400 1633984
16:03:56 43783 8192000000 10261560976 10711650304 1360703264 14.244 100 17 34 0 1371219392 6693136 3822992
16:05:40 83450 8192000000 10257740144 10726539264 1363725392 14.271 100 6 12 0 1375189568 9972688 1491488
16:08:04 82930 8192000000 10284205984 10748813312 1381945296 14.434 100 11 22 0 1393171520 9197056 2029168
16:10:47 38655 8192000000 10285828528 10740060160 1373334032 14.357 98 23 46 0 1387143376 8417376 5391968
16:12:26 53618 8192000000 10291784736 10754973696 1376650192 14.387 98 25 51 0 1393005200 10474512 5880496
16:14:34 38380 8192000000 10285855200 10744713216 1380089728 14.417 99 19 38 0 1397243088 12621504 4531856
16:16:16 61172 8192000000 10303549856 10753421312 1382077200 14.435 99 27 54 1 1398870000 10923648 5869152
16:18:19 23792 8192000000 10314131312 10762076160 1387170000 14.481 99 34 68 0 1405295216 10783136 7342080
16:19:44 44298 8192000000 10287266000 10753564672 1379289136 14.410 99 21 42 0 1394735232 10984336 4461760
16:22:10 99069 8192000000 10259597584 10722082816 1301626816 13.710 95 8 16 0 1317720384 14480576 1612992
16:24:58 42777 8192000000 10278196096 10740330496 1375686960 14.378 100 11 22 0 1390974256 13160768 2126528
16:26:42 61785 8192000000 10278468272 10744475648 1371780448 14.343 99 9 18 0 1381228032 7581584 1866000
16:28:46 60875 8192000000 10280342832 10738962432 1359542752 14.233 98 18 36 0 1375807040 11776496 4487792
16:30:49 96710 8192000000 10267333136 10747932672 1311681568 13.801 95 9 18 0 1332161696 18509120 1971008
16:33:52 28684 8192000000 10281913232 10729963520 1349849856 14.146 98 30 60 0 1367288736 10610528 6828352
16:35:22 42846 8192000000 10295395872 10745311232 1353238256 14.177 98 15 30 0 1373208656 16115296 3855104
16:37:14 78257 8192000000 9432060864 9747927040 558580720 6.383 41 5 10 0 566552480 7348032 623728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-09-2022 16:39:39 to 30-09-2022 16:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 73 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2022 16:39:56 to 30-09-2022 16:39:58.

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          246321
MON$OLDEST_ACTIVE               247777
MON$OLDEST_SNAPSHOT             247777
MON$NEXT_TRANSACTION            247777
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                       2043552
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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'

30.09.22 16:39:59. 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-09-30 16:40:02 No severe PSQL-related problems occured
30.09.22 16:40:03. End of report.