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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-07 22:38:44
DTS_END                         2023-03-07 22:38:44.3470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55972
TRN_ID                          240934
ATT_ID                          646
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: 9612.18 20218 1153462
customer order: creation 170.99 335 20519
customer order: refuse 14.93 303 1791
order to supplier: creation 46.29 1227 5555
order to supplier: removal 3.48 105789 417
invoice (draft): creation 45.78 2914 5493
invoice (draft): removal 55.99 728 6719
invoice accept: apply 42.90 16269 5148
invoice accept: cancel 51.00 682 6120
customer reserve: creation 3963.93 161 475671
customer reserve: removal 4913.25 59 589590
realization accept: apply 85.24 188 10229
realization accept: cancel 88.78 110 10653
payment from customer: creation 59.09 133 7091
payment from customer: removal 5.43 75 652
payment to supplier: creation 49.28 175 5914
payment to supplier: removal 3.37 69 404
service: total inventory turnovers 4.65 999 558
service: total monetary turnovers 6.93 40 832
service: aggregate perf. data 0.85 22371 102
service: refresh index statistics 0.03 251742 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-03-2023 22:39:37 to 07-03-2023 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4036
TEST_TIME 2 4614
TEST_TIME 3 3763
TEST_TIME 4 3640
TEST_TIME 5 4132
TEST_TIME 6 4072
TEST_TIME 7 4242
TEST_TIME 8 4505
TEST_TIME 9 4637
TEST_TIME 10 4869
TEST_TIME 11 5032
TEST_TIME 12 5279
TEST_TIME 13 5604
TEST_TIME 14 5774
TEST_TIME 15 5924
TEST_TIME 16 6190
TEST_TIME 17 6232
TEST_TIME 18 6571
TEST_TIME 19 6449
TEST_TIME 20 7256
TEST_TIME 21 7380
TEST_TIME 22 8261
TEST_TIME 23 8090
TEST_TIME 24 8981
TEST_TIME 25 8822
TEST_TIME 26 8895
TEST_TIME 27 9825
TEST_TIME 28 10414
TEST_TIME 29 10448
TEST_TIME 30 10423
TEST_TIME 31 10244
TEST_TIME 32 10163
TEST_TIME 33 10511
TEST_TIME 34 10509
TEST_TIME 35 10579
TEST_TIME 36 10503
TEST_TIME 37 10285
TEST_TIME 38 10197
TEST_TIME 39 10189
TEST_TIME 40 9998
TEST_TIME 41 9938
TEST_TIME 42 9852
TEST_TIME 43 9820
TEST_TIME 44 9815
TEST_TIME 45 9900
TEST_TIME 46 10016
TEST_TIME 47 9872
TEST_TIME 48 9820
TEST_TIME 49 9765
TEST_TIME 50 9709
TEST_TIME 51 9760
TEST_TIME 52 9631
TEST_TIME 53 9560
TEST_TIME 54 9533
TEST_TIME 55 9478
TEST_TIME 56 9485
TEST_TIME 57 9437
TEST_TIME 58 9433
TEST_TIME 59 9385
TEST_TIME 60 9560
TEST_TIME 61 9611
TEST_TIME 62 9652
TEST_TIME 63 9659
TEST_TIME 64 9760
TEST_TIME 65 9798
TEST_TIME 66 9742
TEST_TIME 67 9727
TEST_TIME 68 9787
TEST_TIME 69 9835
TEST_TIME 70 9826
TEST_TIME 71 9887
TEST_TIME 72 9767
TEST_TIME 73 9759
TEST_TIME 74 9780
TEST_TIME 75 9720
TEST_TIME 76 9673
TEST_TIME 77 9682
TEST_TIME 78 9671
TEST_TIME 79 9635
TEST_TIME 80 9580
TEST_TIME 81 9499
TEST_TIME 82 9518
TEST_TIME 83 9486
TEST_TIME 84 9530
TEST_TIME 85 9532
TEST_TIME 86 9563
TEST_TIME 87 9578
TEST_TIME 88 9606
TEST_TIME 89 9593
TEST_TIME 90 9639
TEST_TIME 91 9697
TEST_TIME 92 9696
TEST_TIME 93 9687
TEST_TIME 94 9728
TEST_TIME 95 9817
TEST_TIME 96 9796
TEST_TIME 97 9805
TEST_TIME 98 9818
TEST_TIME 99 9750
TEST_TIME 100 9747
TEST_TIME 101 9724
TEST_TIME 102 9677
TEST_TIME 103 9723
TEST_TIME 104 9679
TEST_TIME 105 9650
TEST_TIME 106 9638
TEST_TIME 107 9586
TEST_TIME 108 9570
TEST_TIME 109 9533
TEST_TIME 110 9576
TEST_TIME 111 9549
TEST_TIME 112 9539
TEST_TIME 113 9579
TEST_TIME 114 9581
TEST_TIME 115 9574
TEST_TIME 116 9613
TEST_TIME 117 9596
TEST_TIME 118 9596
TEST_TIME 119 9621
TEST_TIME 120 9618

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-03-2023 22:39:41 to 07-03-2023 22:39:44.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1593203 1593203 0 0.00 0 16882 6 0 0
doc_list_biud 1593203 1593203 0 0.00 0 48071 27 0 0
sp_add_invoice_to_stock 5258 5148 110 2.09 33 163568 16270 0 110
sp_cancel_adding_invoice 6120 6120 0 0.00 4 55404 682 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 10832 303 0 0
sp_cancel_customer_reserve 589590 589590 0 0.00 0 48072 59 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 11298 76 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 2 3923 70 0 0
sp_cancel_supplier_invoice 6727 6719 8 0.11 2 23736 728 0 8
sp_cancel_supplier_order 417 417 0 0.00 5 953662 105789 0 0
sp_cancel_write_off 10653 10653 0 0.00 1 16884 110 0 0
sp_client_order 20519 20519 0 0.00 3 20076 336 0 0
sp_customer_reserve 475671 475671 0 0.00 0 80120 162 0 0
sp_fill_shopping_cart 31557 31557 0 0.00 1 15183 36 0 0
sp_fill_shopping_cart_clo_res 475662 475662 0 0.00 0 13454 14 0 0
sp_get_clo_for_invoice 5139 5139 0 0.00 0 31710 328 0 0
sp_kill_cost_storno 17829 17829 0 0.00 0 16881 57 0 0
sp_kill_qstorno_ret_qs2qd 1196969 1196969 0 0.00 0 48068 32 0 0
sp_kill_qty_storno 630658 630658 0 0.00 0 48070 68 0 0
sp_lock_selected_doc 21109 21109 0 0.00 0 8429 8 0 0
sp_make_cost_storno 28373 28373 0 0.00 0 13772 65 0 0
sp_multiply_rows_for_pdistr 28373 28373 0 0.00 0 14688 32 0 0
sp_multiply_rows_for_qdistr 20519 20519 0 0.00 0 12844 151 0 0
sp_pay_from_customer 7091 7091 0 0.00 4 14945 133 0 0
sp_pay_to_supplier 5914 5914 0 0.00 5 17591 175 0 0
sp_payment_common 13005 13005 0 0.00 2 17579 43 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 10827 280 0 0
sp_qd_handle_on_invoice_upd_sts 11259 11259 0 0.00 0 18800 289 0 0
sp_qd_handle_on_reserve_upd_sts 10229 10229 0 0.00 0 20996 55 0 0
sp_reserve_write_off 10229 10229 0 0.00 6 21038 188 0 0
sp_supplier_invoice 5493 5493 0 0.00 6 85923 2914 0 0
sp_supplier_order 5555 5555 0 0.00 5 62013 1227 0 0
srv_aggregate_perf_data 102 102 0 0.00 3037 414427 22372 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1131 111173 43438 0 0
srv_find_qd_qs_mism 1104907 1104907 0 0.00 0 12837 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 44 30177 1000 0 0
srv_make_money_saldo 832 832 0 0.00 0 15532 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 136605 411927 251742 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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-norm:sp_add_invoice_to_stock 5150 5150 0 0.00 0 13825 131 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 4909 96 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 6516 18 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2835 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 8579 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 251 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 986 12 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 19399 1330 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 2959 5 0 0
t$perf-norm:sp_client_order 20520 20520 0 0.00 0 11788 16 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 1697 6 0 0
t$perf-norm:sp_pay_from_customer 7091 7091 0 0.00 0 6155 10 0 0
t$perf-norm:sp_pay_to_supplier 5914 5914 0 0.00 0 10006 11 0 0
t$perf-norm:sp_reserve_write_off 10229 10229 0 0.00 0 7191 13 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 8056 24 0 0
t$perf-norm:sp_supplier_order 5555 5555 0 0.00 0 6362 11 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 25 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 282 5 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 963 15 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 1831 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10496 10276 220 2.09 0 10336 20 0 220
v_all_customers 37826 37826 0 0.00 0 16 0 0 0
v_all_suppliers 30874 30874 0 0.00 0 192 1 0 0
v_all_wares 41038 41038 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 2131 8 0 0
v_cancel_client_order 3582 3582 0 0.00 0 1621 3 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 1510 4 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 773 4 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2401 7 0 0
v_cancel_supplier_invoice 794 778 16 2.01 0 2680 22 0 16
v_cancel_supplier_order 834 834 0 0.00 0 1090 7 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3694 17 0 0
v_max_id_clo_ord 5550 5550 0 0.00 0 1199 1 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 3551 12 0 0
v_max_id_ord_sup 5488 5488 0 0.00 0 5232 5 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 2725 9 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 1282 4 0 0
v_min_id_clo_ord 5550 5550 0 0.00 0 12372 14 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 1653 7 0 0
v_min_id_ord_sup 5488 5488 0 0.00 0 7931 8 0 0
v_min_non_paid_invoice 5914 5914 0 0.00 0 17568 29 0 0
v_min_non_paid_realizn 7091 7091 0 0.00 0 9715 38 0 0
v_our_firm 10216 10216 0 0.00 0 19 1 0 0
v_reserve_write_off 20458 20458 0 0.00 0 5075 8 0 0
x_lock_depdocs_on_canc_invoice 12450 12450 0 0.00 0 22186 107 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 13526 361 0 0
x_make_qstorno_1000_1200 5555 5555 0 0.00 2 61814 1150 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 2 82599 2787 0 0
x_make_qstorno_2100_3300 475669 475669 0 0.00 0 80114 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-03-2023 22:39:45 to 07-03-2023 22:39:49.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:48 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:49 31803 8192000000 9907261968 10221113344 991300752 10.794 100 81 162 0 1017921920 5744144 20877024
20:11:30 20874 8192000000 9950186800 10272935936 1031259056 11.181 100 90 180 0 1063048224 8575232 23213936
20:12:57 11520 8192000000 9981429744 10313138176 1062341728 11.479 100 85 168 1 1093811760 10310912 21159120
20:14:13 7757 8192000000 10009746544 10353086464 1097267232 11.812 100 79 158 0 1127630352 10873728 19489392
20:15:32 5610 8192000000 10064443664 10423017472 1157333840 12.378 100 71 142 0 1184789664 11545680 15910144
20:16:40 2826 8192000000 10116034368 10480443392 1207070176 12.842 100 78 155 0 1237009168 11869536 18069456
20:17:44 2605 8192000000 10151696512 10520449024 1236051120 13.110 100 93 185 0 1270613648 14524096 20038432
20:18:49 1579 8192000000 10168945040 10550493184 1261084736 13.340 100 80 157 0 1291381552 12541040 17755776
20:19:52 2066 8192000000 10202915376 10586619904 1285606128 13.564 100 86 170 1 1317482224 12930304 18945792
20:20:58 2039 8192000000 10212461872 10602754048 1300413936 13.699 100 78 154 1 1330079088 12234144 17431008
20:22:02 14115 8192000000 10207065632 10603577344 1306388784 13.753 100 46 86 2 1324188608 8089952 9709872
20:23:18 2795 8192000000 10215695648 10619236352 1319303232 13.870 100 50 100 0 1338992560 8799056 10890272
20:24:22 9756 8192000000 10210868224 10626547712 1322100240 13.896 100 24 48 0 1334274288 6712688 5461360
20:25:35 52550 8192000000 10218756224 10640715776 1329757584 13.965 100 27 52 0 1341234000 5662320 5814096
20:27:28 9078 8192000000 10216650864 10640719872 1332663840 13.991 100 17 34 0 1342200624 5463264 4073520
20:28:41 11124 8192000000 10217019392 10635149312 1306104560 13.751 98 13 26 0 1314954896 5860960 2989376
20:29:53 6642 8192000000 10184525088 10593107968 1274965024 13.467 98 17 36 0 1284366144 5504240 3896880
20:30:59 37401 8192000000 10147121232 10549960704 1188806800 12.672 92 25 50 0 1199569504 5242304 5520400
20:32:41 7034 8192000000 10131086112 10534035456 1210910048 12.878 96 16 33 0 1219850304 5323312 3616944
20:33:50 10394 8192000000 10157266368 10557386752 1242061424 13.165 98 17 35 0 1251770048 5222864 4485760
20:35:01 52953 8192000000 10190197024 10589974528 1287044576 13.577 100 22 44 0 1298458592 4834240 6579776
20:36:54 9240 8192000000 10185778784 10603008000 1299181648 13.688 100 18 37 0 1308382784 4861168 4339968
20:38:05 8200 8192000000 10211276800 10622402560 1317471168 13.854 100 20 40 0 1326670224 4439472 4759584
20:39:24 57391 8192000000 10247614624 10661191680 1339618336 14.054 100 41 82 0 1356012272 7201488 9192448
20:41:24 111172 8192000000 10272642624 10700627968 1363058048 14.265 100 38 76 1 1391105312 19200960 8846304
20:44:20 20899 8192000000 10286924944 10712920064 1374670208 14.369 100 31 62 0 1399561584 17554640 7336736
20:45:47 10709 8192000000 10312909712 10734157824 1392801968 14.531 100 51 102 1 1424360896 20068912 11490016
20:47:06 2355 8192000000 10329051584 10743922688 1397761728 14.575 100 70 137 1 1436097200 22393424 15942048
20:48:12 9385 8192000000 10334072768 10758606848 1407579312 14.662 100 48 96 0 1445473744 25396208 12498224
20:49:27 11290 8192000000 10336480496 10761322496 1405132544 14.641 100 49 98 0 1447996352 30688832 12174976
20:50:42 6095 8192000000 10361998928 10786209792 1413116480 14.712 100 58 116 0 1469552448 41699040 14736928
20:52:07 4180 8192000000 10364235872 10791157760 1421969584 14.790 100 61 119 2 1475217616 38439408 14808624
20:53:16 5927 8192000000 10354474432 10776616960 1389154064 14.498 98 60 117 1 1445490272 41219008 15117200
20:54:39 4057 8192000000 10389083024 10809106432 1428987760 14.852 100 73 147 1 1489395488 41346032 19061696
20:55:50 1126 8192000000 10385170256 10794602496 1411908864 14.701 99 92 183 1 1482834496 48256448 22669184
20:56:54 2555 8192000000 10358295344 10776043520 1391289152 14.517 98 60 118 0 1456110432 48556992 16264288
20:58:07 2394 8192000000 10395088160 10804228096 1416918304 14.745 100 86 171 1 1502280480 63353072 22009104
20:59:13 9846 8192000000 10375471968 10798575616 1410504000 14.688 100 48 95 0 1482405536 58807120 13094416
21:00:31 10378 8192000000 10344089488 10779041792 1391193648 14.517 100 34 68 0 1455375264 54268736 9912880
21:01:52 19661 8192000000 10354135920 10793549824 1391211584 14.517 100 29 58 1 1460146048 60244384 8690080
21:03:29 26342 8192000000 10341625616 10779668480 1394944992 14.550 100 35 69 0 1448611456 43867808 9798656
21:04:58 28687 8192000000 10321690272 10769293312 1351935536 14.165 99 22 44 1 1424564848 65734944 6894368
21:06:29 29031 8192000000 10262184384 10702655488 1312143232 13.806 96 16 32 0 1328827840 13038832 3645776
21:07:59 49142 8192000000 10248558048 10688466944 1300404208 13.699 96 7 14 0 1310349120 8761072 1183840
21:09:58 69954 8192000000 10272402816 10720124928 1334697584 14.010 98 29 58 1 1358741248 17165856 6877808
21:12:13 99915 8192000000 10260954208 10703728640 1277815088 13.493 94 9 18 0 1296743264 17094928 1833248
21:14:55 85655 8192000000 10283762432 10715017216 1357839472 14.218 100 41 82 1 1375854816 8729536 9285808
21:17:24 15361 8192000000 10282361168 10714320896 1361729040 14.253 100 44 88 1 1379728544 8548128 9451376
21:18:41 37893 8192000000 10291558640 10728132608 1364678304 14.279 99 46 88 1 1384844336 10092112 10073920
21:20:20 33150 8192000000 10291369264 10729390080 1374017296 14.363 100 21 42 0 1392574272 13744064 4812912
21:21:59 71352 8192000000 10289520368 10740355072 1359576576 14.234 99 31 60 0 1390782528 23392640 7813312
21:24:23 56573 8192000000 10269958256 10729738240 1338492384 14.044 97 5 10 0 1353436512 13870560 1073568
21:26:37 33778 8192000000 10283630240 10733674496 1368465856 14.313 100 9 18 0 1388578048 18080448 2031744
21:28:21 78159 8192000000 10271317984 10733273088 1358023664 14.220 99 13 26 0 1378455872 17173056 3259152
21:30:39 74930 8192000000 10284173856 10738475008 1376797632 14.388 100 17 34 0 1394842080 13943408 4101040
21:32:58 68859 8192000000 10285056464 10755002368 1385562224 14.466 100 8 16 0 1401744480 14469936 1712320
21:35:07 32462 8192000000 10304395472 10754162688 1390424576 14.510 100 32 63 0 1404349744 6615216 7309952
21:36:46 52320 8192000000 10296423936 10752835584 1383308864 14.446 99 16 32 1 1401325248 14505872 3510512
21:38:47 87710 8192000000 10296574032 10755526656 1376744080 14.387 98 8 16 0 1391238576 13213024 1281472
21:41:34 73889 8192000000 10300881808 10763214848 1378130864 14.400 98 8 16 0 1392073824 12418304 1524656
21:43:50 45792 8192000000 10301016176 10739916800 1339524688 14.053 96 35 69 0 1354274128 6837024 7912416
21:45:39 17704 8192000000 10296490464 10733649920 1359389456 14.232 98 40 75 1 1378992928 11517200 8086272
21:46:59 62780 8192000000 10253762672 10696425472 1248846032 13.228 91 14 28 0 1263085568 11306912 2932624
21:49:04 45361 8192000000 10267008896 10723880960 1355080464 14.193 100 16 32 0 1376858144 17737808 4039872
21:50:51 82652 8192000000 10278353904 10729766912 1346914272 14.120 98 31 60 0 1364120784 10096016 7110496
21:53:18 14678 8192000000 10261739296 10715291648 1351321648 14.159 99 28 56 0 1373315216 15387024 6606544
21:54:38 45315 8192000000 10273301888 10726825984 1340060160 14.058 97 12 24 0 1355713248 13078832 2574256
21:56:33 32713 8192000000 10260680768 10712342528 1327280656 13.943 97 18 36 0 1341061248 9629712 4150880
21:58:12 43627 8192000000 10246500848 10702393344 1351103312 14.157 100 9 18 0 1362058000 8931024 2023664
21:59:55 73037 8192000000 10240238496 10692268032 1318808624 13.866 97 9 18 0 1331178112 10242464 2127024
22:02:13 70282 8192000000 10241186480 10702450688 1327004432 13.940 98 21 43 0 1341307280 9196416 5106432
22:04:23 72224 8192000000 10246340592 10703241216 1311205584 13.797 97 19 38 0 1326305648 10771072 4328992
22:06:36 22057 8192000000 10260947728 10700693504 1332071248 13.986 99 29 58 0 1345018928 6780656 6167024
22:08:06 33018 8192000000 10275758096 10718638080 1360891824 14.245 100 31 62 0 1376719568 9229760 6597984
22:09:40 77182 8192000000 10294314160 10753093632 1363887456 14.272 98 14 28 0 1380023408 13455216 2680736
22:12:03 44332 8192000000 10286780656 10743693312 1360019552 14.238 98 14 28 2 1374965728 11814240 3131936
22:13:54 77329 8192000000 10292406912 10746056704 1343854192 14.092 97 17 31 0 1362621040 15211504 3555344
22:16:12 38512 8192000000 10299941232 10737819648 1353956848 14.183 97 37 73 0 1370611312 8642576 8011888
22:17:53 59481 8192000000 10284145840 10750574592 1387343856 14.482 100 24 48 0 1401006624 8072608 5590160
22:19:58 46121 8192000000 10294738112 10754179072 1383855360 14.451 100 19 38 0 1400764880 12288144 4621376
22:21:44 69750 8192000000 10279315472 10747469824 1360195200 14.239 98 8 16 0 1370878064 8961840 1721024
22:24:12 56540 8192000000 10284246464 10742685696 1359829792 14.236 98 15 28 0 1373263552 9410464 4023296
22:26:59 61502 8192000000 10293177168 10760179712 1388869024 14.496 100 31 62 0 1412455872 16450304 7136544
22:29:05 35197 8192000000 10285799280 10749812736 1368020560 14.309 98 13 26 0 1378349792 7371088 2958144
22:30:40 27952 8192000000 10303971120 10746277888 1377416688 14.393 99 27 54 0 1392204864 8584928 6203248
22:32:11 79390 8192000000 10264528960 10717126656 1316135936 13.842 95 12 24 0 1327324048 8764256 2423856
22:34:30 63204 8192000000 10277873456 10733899776 1343589264 14.090 98 23 46 0 1361372064 12502640 5280160
22:36:46 31016 8192000000 10279492576 10721517568 1343640560 14.090 98 42 84 0 1366309968 13633504 9035904
22:38:17 43540 8192000000 9350581120 9650946048 496008640 5.709 37 10 20 0 503569600 5718000 1842960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-03-2023 22:39:51 to 07-03-2023 22:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 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 07-03-2023 22:40:07 to 07-03-2023 22:40:09.

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          241186
MON$OLDEST_ACTIVE               241187
MON$OLDEST_SNAPSHOT             241187
MON$NEXT_TRANSACTION            241187
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                       2021696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:1148: 
:1149: mscs-test-161.budzdorov.ru    Tue Mar  7 22:39:06 2023
:1150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1151: 
--- end of diff output ---

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

07.03.23 22:40:11. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-07 22:40:13 No severe PSQL-related problems occured
07.03.23 22:40:14. End of report.