Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.04.2022 22:38
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 16495660 kB
MemAvailable 31525260 kB
Buffers 0 kB
Cached 15191716 kB
SwapCached 3208 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-28 22:38:17
DTS_END                         2022-04-28 22:38:17.4830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43822
TRN_ID                          247502
ATT_ID                          647
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: 9494.53 17857 1139344
customer order: creation 180.34 356 21641
customer order: refuse 16.10 305 1932
order to supplier: creation 48.53 1237 5823
order to supplier: removal 3.53 93185 423
invoice (draft): creation 47.60 3256 5712
invoice (draft): removal 54.08 793 6490
invoice accept: apply 43.77 16900 5252
invoice accept: cancel 48.32 790 5798
customer reserve: creation 3997.43 170 479691
customer reserve: removal 4753.60 53 570432
realization accept: apply 86.31 175 10357
realization accept: cancel 80.17 109 9620
payment from customer: creation 61.39 98 7367
payment from customer: removal 6.08 87 729
payment to supplier: creation 51.50 118 6180
payment to supplier: removal 3.54 126 425
service: total inventory turnovers 4.88 945 585
service: total monetary turnovers 6.36 33 763
service: aggregate perf. data 1.00 15716 120
service: refresh index statistics 0.03 222686 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-04-2022 22:39:07 to 28-04-2022 22:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4550
TEST_TIME 2 4591
TEST_TIME 3 5028
TEST_TIME 4 5724
TEST_TIME 5 5186
TEST_TIME 6 5449
TEST_TIME 7 5437
TEST_TIME 8 5958
TEST_TIME 9 6230
TEST_TIME 10 6065
TEST_TIME 11 6658
TEST_TIME 12 6704
TEST_TIME 13 6664
TEST_TIME 14 6772
TEST_TIME 15 6870
TEST_TIME 16 7149
TEST_TIME 17 7335
TEST_TIME 18 7685
TEST_TIME 19 7789
TEST_TIME 20 8455
TEST_TIME 21 8515
TEST_TIME 22 8722
TEST_TIME 23 8876
TEST_TIME 24 9097
TEST_TIME 25 9356
TEST_TIME 26 9685
TEST_TIME 27 9933
TEST_TIME 28 10299
TEST_TIME 29 10931
TEST_TIME 30 10900
TEST_TIME 31 10694
TEST_TIME 32 10643
TEST_TIME 33 10599
TEST_TIME 34 10530
TEST_TIME 35 10542
TEST_TIME 36 10465
TEST_TIME 37 10429
TEST_TIME 38 10385
TEST_TIME 39 10341
TEST_TIME 40 10137
TEST_TIME 41 10021
TEST_TIME 42 10065
TEST_TIME 43 10135
TEST_TIME 44 10059
TEST_TIME 45 10010
TEST_TIME 46 9938
TEST_TIME 47 9913
TEST_TIME 48 9855
TEST_TIME 49 9860
TEST_TIME 50 9781
TEST_TIME 51 9761
TEST_TIME 52 9718
TEST_TIME 53 9760
TEST_TIME 54 9731
TEST_TIME 55 9642
TEST_TIME 56 9620
TEST_TIME 57 9627
TEST_TIME 58 9620
TEST_TIME 59 9569
TEST_TIME 60 9602
TEST_TIME 61 9797
TEST_TIME 62 9780
TEST_TIME 63 9804
TEST_TIME 64 9858
TEST_TIME 65 9839
TEST_TIME 66 9916
TEST_TIME 67 9857
TEST_TIME 68 9902
TEST_TIME 69 10036
TEST_TIME 70 9991
TEST_TIME 71 9887
TEST_TIME 72 9812
TEST_TIME 73 9825
TEST_TIME 74 9806
TEST_TIME 75 9738
TEST_TIME 76 9761
TEST_TIME 77 9747
TEST_TIME 78 9690
TEST_TIME 79 9657
TEST_TIME 80 9598
TEST_TIME 81 9543
TEST_TIME 82 9525
TEST_TIME 83 9541
TEST_TIME 84 9497
TEST_TIME 85 9568
TEST_TIME 86 9530
TEST_TIME 87 9500
TEST_TIME 88 9506
TEST_TIME 89 9598
TEST_TIME 90 9612
TEST_TIME 91 9592
TEST_TIME 92 9645
TEST_TIME 93 9643
TEST_TIME 94 9712
TEST_TIME 95 9741
TEST_TIME 96 9771
TEST_TIME 97 9739
TEST_TIME 98 9817
TEST_TIME 99 9757
TEST_TIME 100 9696
TEST_TIME 101 9687
TEST_TIME 102 9700
TEST_TIME 103 9674
TEST_TIME 104 9631
TEST_TIME 105 9618
TEST_TIME 106 9591
TEST_TIME 107 9562
TEST_TIME 108 9505
TEST_TIME 109 9544
TEST_TIME 110 9540
TEST_TIME 111 9514
TEST_TIME 112 9479
TEST_TIME 113 9492
TEST_TIME 114 9506
TEST_TIME 115 9513
TEST_TIME 116 9487
TEST_TIME 117 9521
TEST_TIME 118 9553
TEST_TIME 119 9567
TEST_TIME 120 9497

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-04-2022 22:39:11 to 28-04-2022 22:39:14.

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 1584248 1584248 0 0.00 0 16885 6 0 0
doc_list_biud 1584247 1584247 0 0.00 0 81952 25 0 0
sp_add_invoice_to_stock 5380 5252 128 2.37 36 161130 16900 0 128
sp_cancel_adding_invoice 5798 5798 0 0.00 3 58440 790 0 0
sp_cancel_client_order 1932 1932 0 0.00 4 14930 306 0 0
sp_cancel_customer_reserve 570432 570432 0 0.00 1 23979 53 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 3 6299 87 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 8759 127 0 0
sp_cancel_supplier_invoice 6502 6490 12 0.18 2 81963 793 0 12
sp_cancel_supplier_order 423 423 0 0.00 6 1077160 93186 0 0
sp_cancel_write_off 9620 9620 0 0.00 1 7854 110 0 0
sp_client_order 21641 21641 0 0.00 3 24163 357 0 0
sp_customer_reserve 479691 479691 0 0.00 0 92815 170 0 0
sp_fill_shopping_cart 33168 33168 0 0.00 1 9162 36 0 0
sp_fill_shopping_cart_clo_res 479686 479686 0 0.00 0 13394 13 0 0
sp_get_clo_for_invoice 5247 5247 0 0.00 0 22737 242 0 0
sp_kill_cost_storno 16572 16572 0 0.00 0 6496 54 0 0
sp_kill_qstorno_ret_qs2qd 1157397 1157397 0 0.00 0 81947 29 0 0
sp_kill_qty_storno 610298 610298 0 0.00 0 81951 64 0 0
sp_lock_selected_doc 21764 21764 0 0.00 0 11108 8 0 0
sp_make_cost_storno 29150 29150 0 0.00 0 10581 52 0 0
sp_multiply_rows_for_pdistr 29150 29150 0 0.00 0 8785 25 0 0
sp_multiply_rows_for_qdistr 21641 21641 0 0.00 0 11064 157 0 0
sp_pay_from_customer 7367 7367 0 0.00 4 12533 98 0 0
sp_pay_to_supplier 6180 6180 0 0.00 5 12656 119 0 0
sp_payment_common 13547 13547 0 0.00 1 12496 26 0 0
sp_qd_handle_on_cancel_clo 1932 1932 0 0.00 0 14924 282 0 0
sp_qd_handle_on_invoice_upd_sts 11045 11045 0 0.00 0 14331 317 0 0
sp_qd_handle_on_reserve_upd_sts 10356 10356 0 0.00 0 19868 51 0 0
sp_reserve_write_off 10357 10357 0 0.00 6 19893 176 0 0
sp_supplier_invoice 5712 5712 0 0.00 6 93401 3256 0 0
sp_supplier_order 5823 5823 0 0.00 6 22251 1237 0 0
srv_aggregate_perf_data 121 120 1 0.82 2462 114273 15717 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2102 160087 43833 0 0
srv_find_qd_qs_mism 1089928 1089928 0 0.00 0 16884 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 39 46841 946 0 0
srv_make_money_saldo 763 763 0 0.00 1 5989 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 151811 322162 222687 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 5253 5253 0 0.00 0 7592 168 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 1 4741 89 0 0
t$perf-norm:sp_cancel_client_order 1932 1932 0 0.00 0 5900 22 0 0
t$perf-norm:sp_cancel_customer_reserve 766 766 0 0.00 0 4720 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 8094 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 904 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 823 8 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 20693 1473 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 1533 5 0 0
t$perf-norm:sp_client_order 21641 21641 0 0.00 0 7302 17 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 660 4 0 0
t$perf-norm:sp_pay_from_customer 7367 7367 0 0.00 0 3918 8 0 0
t$perf-norm:sp_pay_to_supplier 6180 6180 0 0.00 0 6941 9 0 0
t$perf-norm:sp_reserve_write_off 10357 10357 0 0.00 0 4668 9 0 0
t$perf-norm:sp_supplier_invoice 5712 5712 0 0.00 0 4569 17 0 0
t$perf-norm:sp_supplier_order 5823 5823 0 0.00 0 9337 15 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 2308 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 26 1 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 985 23 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 3972 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 6 0 0
v_add_invoice_to_stock 10750 10494 256 2.38 0 6176 18 0 256
v_all_customers 39704 39704 0 0.00 0 92 1 0 0
v_all_suppliers 32086 32086 0 0.00 0 17 1 0 0
v_all_wares 43282 43282 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 506 6 0 0
v_cancel_client_order 3864 3864 0 0.00 0 3576 5 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 3963 11 0 0
v_cancel_customer_reserve 1532 1532 0 0.00 0 805 3 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3424 11 0 0
v_cancel_supplier_invoice 882 858 24 2.72 0 3747 21 0 24
v_cancel_supplier_order 846 846 0 0.00 0 1414 8 0 0
v_cancel_write_off 2032 2032 0 0.00 0 7814 24 0 0
v_max_id_clo_ord 5823 5823 0 0.00 0 1260 2 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 1421 11 0 0
v_max_id_ord_sup 5704 5704 0 0.00 0 4650 4 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 471 2 0 0
v_max_non_paid_realizn 3861 3861 0 0.00 0 8770 6 0 0
v_min_id_clo_ord 5823 5823 0 0.00 0 4812 9 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 3161 4 0 0
v_min_id_ord_sup 5704 5704 0 0.00 0 6009 8 0 0
v_min_non_paid_invoice 6180 6180 0 0.00 0 8710 19 0 0
v_min_non_paid_realizn 7367 7367 0 0.00 0 11826 16 0 0
v_our_firm 10590 10590 0 0.00 0 84 1 0 0
v_reserve_write_off 20712 20712 0 0.00 0 5872 7 0 0
x_lock_depdocs_on_canc_invoice 11859 11859 0 0.00 0 26845 120 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 14960 368 0 0
x_make_qstorno_1000_1200 5823 5823 0 0.00 2 22242 1151 0 0
x_make_qstorno_1200_2000 5712 5712 0 0.00 2 93385 3135 0 0
x_make_qstorno_2100_3300 479691 479691 0 0.00 0 92797 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-04-2022 22:39:15 to 28-04-2022 22:39:19.

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
20:08:21 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:22 24843 8192000000 9891294992 10201387008 973888576 10.625 100 83 166 0 1001130848 5962768 21279504
20:10:55 9484 8192000000 9946620384 10267058176 1028754352 11.156 100 87 174 0 1059111152 8065344 22291456
20:12:12 17101 8192000000 9982068848 10313252864 1064107376 11.496 100 93 185 0 1096720992 10038176 22575440
20:13:32 21420 8192000000 10034160864 10384236544 1113710016 11.968 100 73 146 1 1142029440 10967488 17351936
20:14:59 9242 8192000000 10076692944 10438946816 1168371376 12.482 100 78 155 0 1198855776 12399888 18084512
20:16:13 6003 8192000000 10128870736 10497916928 1219331872 12.955 100 81 161 0 1250156736 12930608 17894256
20:17:22 2263 8192000000 10157966016 10531663872 1243117280 13.175 100 86 171 2 1276338224 13535408 19685536
20:18:26 1855 8192000000 10195209088 10577223680 1274880944 13.466 100 94 188 3 1310477440 14488272 21108224
20:19:29 5062 8192000000 10201503776 10594181120 1294889648 13.649 100 76 153 2 1323461952 11823520 16748784
20:20:35 6229 8192000000 10190619872 10598563840 1302589216 13.719 100 40 79 1 1318484448 7430256 8464976
20:21:42 71779 8192000000 10213215376 10622971904 1317538224 13.854 100 25 50 1 1328379488 5351360 5489904
20:23:54 3740 8192000000 10217678560 10636247040 1323221440 13.906 100 49 98 2 1342466896 8254176 10991280
20:25:00 11568 8192000000 10214892160 10636664832 1328535008 13.954 100 16 32 0 1337388496 4967904 3885584
20:26:12 51812 8192000000 10233052272 10650472448 1334060992 14.004 100 48 94 1 1351412912 5835920 11516000
20:28:07 7170 8192000000 10212167088 10636988416 1327414480 13.944 100 30 60 1 1341454752 6313984 7726288
20:29:15 8600 8192000000 10180358416 10581938176 1261043216 13.340 98 32 62 1 1274272544 5604640 7624688
20:30:24 15791 8192000000 10168812736 10570973184 1243788368 13.181 97 22 44 2 1254345680 5682640 4874672
20:31:44 5216 8192000000 10122879104 10526093312 1222750800 12.987 98 17 34 0 1232388528 5965008 3672720
20:32:50 5523 8192000000 10133648704 10536554496 1227984960 13.035 98 26 52 0 1240155712 5414544 6756208
20:33:56 6941 8192000000 10160555120 10565992448 1267974464 13.403 100 39 78 5 1283857680 6421232 9461984
20:35:07 11463 8192000000 10178288560 10590154752 1276781744 13.484 99 12 24 1 1285098192 5264960 3051488
20:36:20 45192 8192000000 10183341552 10603053056 1298675840 13.683 100 27 53 0 1310980464 5917888 6386736
20:38:12 8239 8192000000 10218441168 10629160960 1320859440 13.884 100 21 42 1 1330949760 4885840 5204480
20:39:27 10240 8192000000 10250818640 10661154816 1342778864 14.082 100 51 101 1 1362259456 8453104 11027488
20:40:44 26743 8192000000 10259371344 10679341056 1357185744 14.212 100 41 81 3 1377246512 10634560 9426208
20:42:13 34776 8192000000 10279054880 10701598720 1370309856 14.330 100 35 70 2 1389008448 9996128 8702464
20:43:57 26188 8192000000 10286754256 10716782592 1376716752 14.387 100 28 56 0 1395589456 12287312 6585392
20:45:30 24211 8192000000 10306859424 10734682112 1387058976 14.480 100 37 74 1 1413589408 17555072 8975360
20:47:02 8106 8192000000 10327662368 10746310656 1398365152 14.580 100 63 126 1 1434318960 20276128 15677680
20:48:16 13666 8192000000 10320882928 10746040320 1395525920 14.555 100 60 120 0 1432029040 21941600 14561520
20:49:37 4543 8192000000 10312184000 10728095744 1380151200 14.418 99 65 130 1 1417809904 21527328 16131376
20:50:48 22749 8192000000 10296391504 10714890240 1346985744 14.120 97 52 104 2 1380989632 21629488 12374400
20:52:22 3138 8192000000 10325410480 10728525824 1376205584 14.383 100 85 169 2 1425201984 27808288 21188112
20:53:30 3240 8192000000 10308253856 10726830080 1368554896 14.314 99 43 82 1 1407919664 28861600 10503168
20:54:39 2511 8192000000 10331715104 10738962432 1373458000 14.358 99 80 157 1 1432464720 39393104 19613616
20:55:45 3684 8192000000 10324509456 10735767552 1361922496 14.255 99 65 130 1 1422282080 43003216 17356368
20:56:54 2287 8192000000 10340101152 10744360960 1378024800 14.399 100 88 175 0 1444291360 42819840 23446720
20:58:01 5949 8192000000 10359310624 10778734592 1389541744 14.502 99 62 124 0 1454515136 48886736 16086656
20:59:18 3569 8192000000 10359636816 10773217280 1396412288 14.563 100 85 169 0 1458485264 41971088 20101888
21:00:24 17150 8192000000 10323497904 10753798144 1369293200 14.321 99 31 62 2 1420157408 42187792 8676416
21:02:05 9171 8192000000 10339239792 10772623360 1383417232 14.447 100 35 70 0 1447200368 54150560 9632576
21:03:19 33693 8192000000 10340015984 10780590080 1384515232 14.457 100 26 52 0 1447148176 55455440 7177504
21:04:59 23803 8192000000 10316342528 10762584064 1370720928 14.334 100 29 58 0 1426256288 47936848 7598512
21:06:28 60851 8192000000 10274107776 10744700928 1331768064 13.983 98 13 26 0 1392020496 56100768 4151664
21:08:34 52526 8192000000 10261789952 10695987200 1313180544 13.815 96 16 31 0 1325084848 8623136 3281168
21:10:27 50162 8192000000 10272289088 10713055232 1353912832 14.183 99 17 34 1 1367180976 9672512 3595632
21:12:24 34051 8192000000 10294106288 10735644672 1377509216 14.394 100 29 59 1 1392717680 8081472 7126992
21:13:59 54083 8192000000 10300078672 10743664640 1387478096 14.483 100 14 28 1 1400868064 10368048 3021920
21:15:53 44146 8192000000 10313532496 10758488064 1394876016 14.549 100 24 49 1 1408274400 8314400 5083984
21:17:42 89518 8192000000 10302162128 10748346368 1373579248 14.359 98 43 85 3 1393622272 9783024 10260000
21:20:21 64324 8192000000 10301476912 10763497472 1391005936 14.515 100 37 73 2 1418759616 19115104 8638576
21:22:38 2100 8192000000 10299740976 10742960128 1391177776 14.516 100 43 86 1 1406902288 6174320 9550192
21:23:41 3834 8192000000 10304860960 10742403072 1386623920 14.476 100 49 93 1 1403461632 6739472 10098240
21:24:47 38641 8192000000 10317059568 10765873152 1397510608 14.573 100 35 69 0 1417399920 11959616 7929696
21:26:27 40208 8192000000 10289879904 10752065536 1374248048 14.365 98 11 22 0 1399067120 22091216 2727856
21:28:10 112875 8192000000 10269630432 10727546880 1341150080 14.068 96 12 24 0 1357017824 12961968 2905776
21:31:04 57871 8192000000 10268131936 10724282368 1336573200 14.027 97 26 53 0 1352576400 10307248 5695952
21:33:03 66367 8192000000 10268432944 10724995072 1324949936 13.922 96 17 34 0 1339677056 11169280 3557840
21:35:14 69709 8192000000 10273301968 10722885632 1284228048 13.552 93 20 40 0 1298168384 9440640 4499696
21:38:20 60820 8192000000 10277738928 10728443904 1308608720 13.773 95 16 32 1 1328964000 16448288 3906992
21:40:23 50136 8192000000 10251278656 10708393984 1335228432 14.014 98 28 56 3 1350061904 8634352 6199120
21:42:13 35096 8192000000 10286249584 10733756416 1351622336 14.162 99 33 66 0 1376084400 17100960 7361104
21:43:50 68572 8192000000 10273320560 10726604800 1324924256 13.921 96 13 26 1 1339539408 11633328 2981824
21:46:03 53436 8192000000 10291649936 10741161984 1364030336 14.274 100 32 64 0 1388537840 17720544 6786960
21:48:06 46689 8192000000 10303810848 10745896960 1339272000 14.051 97 19 38 1 1358178096 14723728 4182368
21:49:53 160087 8192000000 10277662512 10727407616 1302982960 13.722 94 30 60 0 1327206736 17649552 6574224
21:54:36 55666 8192000000 10275629936 10744303616 1363368816 14.268 99 5 10 0 1376180560 11648128 1163616
21:56:32 63268 8192000000 10279151312 10743291904 1366921264 14.299 99 16 32 1 1382547552 12180720 3445568
21:58:53 65026 8192000000 10278339312 10741948416 1360677408 14.243 98 20 39 1 1376822048 11603232 4541408
22:01:00 40546 8192000000 10281688992 10751942656 1378852496 14.406 100 12 24 0 1393803520 12360448 2590576
22:02:48 63029 8192000000 10288686224 10755801088 1365005360 14.282 98 15 30 0 1379316800 10853968 3457472
22:05:14 66029 8192000000 10309546352 10764115968 1373492864 14.358 98 18 36 2 1392418944 14994048 3932032
22:07:33 28122 8192000000 10298543520 10743787520 1349316048 14.141 97 32 64 1 1369005904 13167664 6522192
22:09:04 27369 8192000000 10294406224 10745688064 1375277040 14.374 99 24 48 0 1388987088 8651280 5058768
22:10:33 65342 8192000000 10318759456 10776731648 1394564496 14.547 100 13 26 1 1410507792 12937968 3005328
22:12:39 82503 8192000000 10310769824 10783666176 1342769136 14.082 96 4 8 0 1366944528 23589936 585456
22:15:08 47965 8192000000 10297643152 10745413632 1344183456 14.095 97 19 38 0 1360503184 11787040 4532688
22:16:56 128775 8192000000 10268077808 10724896768 1330269568 13.970 96 19 38 2 1347493088 12555648 4667872
22:20:16 28181 8192000000 10273768016 10747359232 1370947184 14.336 100 12 24 0 1389477200 15864608 2665408
22:21:44 50947 8192000000 10263553376 10728722432 1358980816 14.228 99 19 38 0 1372171760 8913264 4277680
22:23:38 57389 8192000000 10253456304 10717155328 1330958048 13.976 97 9 18 0 1342335136 9147952 2229136
22:25:35 79181 8192000000 10256084576 10714333184 1334927376 14.012 98 13 25 0 1346616592 8779888 2909328
22:28:33 66051 8192000000 10265408160 10729897984 1367483904 14.304 100 16 32 0 1380663856 9539296 3640656
22:30:40 53073 8192000000 10278887408 10737659904 1353093264 14.175 98 11 22 0 1370650768 15237728 2319776
22:32:40 43146 8192000000 10261180272 10716258304 1326268528 13.933 97 33 66 2 1343822784 9869808 7684448
22:34:24 49232 8192000000 10275920192 10726076416 1348761152 14.136 99 22 44 1 1367917024 13590384 5565488
22:36:22 84486 8192000000 10284358496 10745077760 1362428832 14.259 99 14 28 1 1377290288 11852416 3009040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-04-2022 22:39:21 to 28-04-2022 22:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 128 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-04-2022 22:39:37 to 28-04-2022 22:39:39.

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

28.04.22 22:39:40. 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-28 22:39:42 No severe PSQL-related problems occured
28.04.22 22:39:43. End of report.