Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2022 01: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 15274016 kB
MemAvailable 30535456 kB
Buffers 0 kB
Cached 16419724 kB
SwapCached 2124 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-10-05 01:38:28
DTS_END                         2022-10-05 01:38:28.6040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34082
TRN_ID                          246358
ATT_ID                          674
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: 9571.62 21190 1148594
customer order: creation 173.01 339 20761
customer order: refuse 15.20 348 1824
order to supplier: creation 47.14 1118 5657
order to supplier: removal 3.72 91793 446
invoice (draft): creation 47.43 3335 5692
invoice (draft): removal 55.49 774 6659
invoice accept: apply 41.83 17171 5019
invoice accept: cancel 50.26 750 6031
customer reserve: creation 3897.68 170 467722
customer reserve: removal 4935.36 53 592243
realization accept: apply 84.33 180 10120
realization accept: cancel 88.56 99 10627
payment from customer: creation 60.52 117 7262
payment from customer: removal 6.36 76 763
payment to supplier: creation 49.37 122 5924
payment to supplier: removal 3.58 80 429
service: total inventory turnovers 4.77 1054 572
service: total monetary turnovers 6.20 24 744
service: aggregate perf. data 0.79 21785 95
service: refresh index statistics 0.03 284419 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2022 01:39:18 to 05-10-2022 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4056
TEST_TIME 2 3742
TEST_TIME 3 4384
TEST_TIME 4 3651
TEST_TIME 5 3949
TEST_TIME 6 4214
TEST_TIME 7 5384
TEST_TIME 8 5704
TEST_TIME 9 5579
TEST_TIME 10 5932
TEST_TIME 11 5870
TEST_TIME 12 6167
TEST_TIME 13 6208
TEST_TIME 14 6214
TEST_TIME 15 6270
TEST_TIME 16 6533
TEST_TIME 17 6617
TEST_TIME 18 6662
TEST_TIME 19 7078
TEST_TIME 20 7684
TEST_TIME 21 8056
TEST_TIME 22 8007
TEST_TIME 23 8441
TEST_TIME 24 8892
TEST_TIME 25 9753
TEST_TIME 26 9941
TEST_TIME 27 10584
TEST_TIME 28 10520
TEST_TIME 29 10268
TEST_TIME 30 10401
TEST_TIME 31 10273
TEST_TIME 32 10298
TEST_TIME 33 10260
TEST_TIME 34 10234
TEST_TIME 35 10285
TEST_TIME 36 10276
TEST_TIME 37 10188
TEST_TIME 38 10019
TEST_TIME 39 9901
TEST_TIME 40 9914
TEST_TIME 41 9969
TEST_TIME 42 9838
TEST_TIME 43 9771
TEST_TIME 44 9731
TEST_TIME 45 9670
TEST_TIME 46 9747
TEST_TIME 47 9931
TEST_TIME 48 9822
TEST_TIME 49 9870
TEST_TIME 50 9733
TEST_TIME 51 9696
TEST_TIME 52 9730
TEST_TIME 53 9677
TEST_TIME 54 9579
TEST_TIME 55 9546
TEST_TIME 56 9582
TEST_TIME 57 9525
TEST_TIME 58 9479
TEST_TIME 59 9566
TEST_TIME 60 9569
TEST_TIME 61 9496
TEST_TIME 62 9695
TEST_TIME 63 9777
TEST_TIME 64 9826
TEST_TIME 65 9744
TEST_TIME 66 9892
TEST_TIME 67 9920
TEST_TIME 68 9894
TEST_TIME 69 9859
TEST_TIME 70 9883
TEST_TIME 71 9867
TEST_TIME 72 9768
TEST_TIME 73 9734
TEST_TIME 74 9735
TEST_TIME 75 9688
TEST_TIME 76 9646
TEST_TIME 77 9654
TEST_TIME 78 9596
TEST_TIME 79 9612
TEST_TIME 80 9543
TEST_TIME 81 9506
TEST_TIME 82 9517
TEST_TIME 83 9559
TEST_TIME 84 9551
TEST_TIME 85 9564
TEST_TIME 86 9566
TEST_TIME 87 9555
TEST_TIME 88 9567
TEST_TIME 89 9533
TEST_TIME 90 9603
TEST_TIME 91 9602
TEST_TIME 92 9649
TEST_TIME 93 9634
TEST_TIME 94 9693
TEST_TIME 95 9664
TEST_TIME 96 9705
TEST_TIME 97 9681
TEST_TIME 98 9597
TEST_TIME 99 9630
TEST_TIME 100 9601
TEST_TIME 101 9600
TEST_TIME 102 9601
TEST_TIME 103 9557
TEST_TIME 104 9547
TEST_TIME 105 9508
TEST_TIME 106 9501
TEST_TIME 107 9474
TEST_TIME 108 9463
TEST_TIME 109 9437
TEST_TIME 110 9458
TEST_TIME 111 9469
TEST_TIME 112 9473
TEST_TIME 113 9475
TEST_TIME 114 9468
TEST_TIME 115 9467
TEST_TIME 116 9527
TEST_TIME 117 9527
TEST_TIME 118 9546
TEST_TIME 119 9595
TEST_TIME 120 9579

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-10-2022 01:39:22 to 05-10-2022 01:39:25.

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 1584362 1584362 0 0.00 0 13825 6 0 0
doc_list_biud 1584358 1584358 0 0.00 0 60639 26 0 0
sp_add_invoice_to_stock 5080 5019 61 1.20 30 195472 17171 0 61
sp_cancel_adding_invoice 6031 6031 0 0.00 4 71567 751 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 10978 349 0 0
sp_cancel_customer_reserve 592243 592243 0 0.00 0 32949 54 0 0
sp_cancel_pay_from_customer 763 763 0 0.00 3 3030 76 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 4775 81 0 0
sp_cancel_supplier_invoice 6661 6659 2 0.03 1 60649 774 0 2
sp_cancel_supplier_order 446 446 0 0.00 4 1150223 91794 0 0
sp_cancel_write_off 10627 10627 0 0.00 1 7889 100 0 0
sp_client_order 20761 20761 0 0.00 3 28317 339 0 0
sp_customer_reserve 467722 467722 0 0.00 0 73827 170 0 0
sp_fill_shopping_cart 32110 32110 0 0.00 1 15949 35 0 0
sp_fill_shopping_cart_clo_res 467714 467714 0 0.00 0 17685 13 0 0
sp_get_clo_for_invoice 5010 5010 0 0.00 0 32156 254 0 0
sp_kill_cost_storno 17850 17850 0 0.00 0 10022 53 0 0
sp_kill_qstorno_ret_qs2qd 1202218 1202218 0 0.00 0 60635 29 0 0
sp_kill_qty_storno 632958 632958 0 0.00 0 60638 63 0 0
sp_lock_selected_doc 21128 21128 0 0.00 0 8313 5 0 0
sp_make_cost_storno 28316 28316 0 0.00 0 12047 51 0 0
sp_multiply_rows_for_pdistr 28314 28314 0 0.00 0 11690 28 0 0
sp_multiply_rows_for_qdistr 20761 20761 0 0.00 0 22720 153 0 0
sp_pay_from_customer 7262 7262 0 0.00 4 15016 118 0 0
sp_pay_to_supplier 5924 5924 0 0.00 4 10870 123 0 0
sp_payment_common 13186 13186 0 0.00 2 14387 29 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 9946 319 0 0
sp_qd_handle_on_invoice_upd_sts 11040 11040 0 0.00 0 19691 296 0 0
sp_qd_handle_on_reserve_upd_sts 10119 10119 0 0.00 0 15964 65 0 0
sp_reserve_write_off 10120 10120 0 0.00 6 16003 181 0 0
sp_supplier_invoice 5692 5692 0 0.00 5 106255 3336 0 0
sp_supplier_order 5657 5657 0 0.00 5 24585 1118 0 0
srv_aggregate_perf_data 95 95 0 0.00 3166 522805 21785 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1960 137228 43864 0 0
srv_find_qd_qs_mism 1101472 1101472 0 0.00 0 13063 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 46 61841 1055 0 0
srv_make_money_saldo 744 744 0 0.00 1 499 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 226449 384834 284420 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5019 5019 0 0.00 0 8484 160 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 2991 82 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 9474 21 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1613 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 763 763 0 0.00 0 3448 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 2194 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 939 13 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 21945 1344 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 4824 12 0 0
t$perf-norm:sp_client_order 20762 20762 0 0.00 0 9741 20 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 3157 9 0 0
t$perf-norm:sp_pay_from_customer 7262 7262 0 0.00 0 4497 9 0 0
t$perf-norm:sp_pay_to_supplier 5924 5924 0 0.00 0 9381 12 0 0
t$perf-norm:sp_reserve_write_off 10120 10120 0 0.00 0 8656 12 0 0
t$perf-norm:sp_supplier_invoice 5692 5692 0 0.00 0 4188 13 0 0
t$perf-norm:sp_supplier_order 5657 5657 0 0.00 0 11801 22 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 178 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 146 4 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 3514 21 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 564 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 7 0 0
v_add_invoice_to_stock 10140 10018 122 1.20 0 4734 19 0 122
v_all_customers 37954 37954 0 0.00 0 25 1 0 0
v_all_suppliers 31444 31444 0 0.00 0 29 1 0 0
v_all_wares 41522 41522 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2772 16 0 0
v_cancel_client_order 3648 3648 0 0.00 0 3578 9 0 0
v_cancel_customer_prepayment 1526 1526 0 0.00 0 3013 9 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 3451 7 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 4712 13 0 0
v_cancel_supplier_invoice 828 824 4 0.48 0 3501 21 0 4
v_cancel_supplier_order 892 892 0 0.00 0 726 4 0 0
v_cancel_write_off 2050 2050 0 0.00 0 5196 19 0 0
v_max_id_clo_ord 5657 5657 0 0.00 0 241 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 9676 30 0 0
v_max_id_ord_sup 5692 5692 0 0.00 0 6036 5 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 206 2 0 0
v_max_non_paid_realizn 3813 3813 0 0.00 0 11345 7 0 0
v_min_id_clo_ord 5657 5657 0 0.00 0 3313 8 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 5917 15 0 0
v_min_id_ord_sup 5692 5692 0 0.00 0 7021 9 0 0
v_min_non_paid_invoice 5924 5924 0 0.00 0 9915 20 0 0
v_min_non_paid_realizn 7262 7262 0 0.00 1 9293 20 0 0
v_our_firm 10466 10466 0 0.00 0 19 1 0 0
v_reserve_write_off 20238 20238 0 0.00 0 4826 8 0 0
x_lock_depdocs_on_canc_invoice 12278 12278 0 0.00 0 21788 116 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 11400 319 0 0
x_make_qstorno_1000_1200 5657 5657 0 0.00 1 24489 1045 0 0
x_make_qstorno_1200_2000 5692 5692 0 0.00 2 106184 3209 0 0
x_make_qstorno_2100_3300 467722 467722 0 0.00 0 73822 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2022 01:39:26 to 05-10-2022 01:39:30.

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
23:08:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:33 34899 8192000000 9907572640 10218647552 985093904 10.734 100 92 184 0 1015968048 6618512 24255632
23:11:17 17642 8192000000 9945144144 10264825856 1025671536 11.127 100 90 180 0 1057979648 9072288 23235824
23:12:52 14137 8192000000 9977876240 10311843840 1066660880 11.520 100 80 160 0 1097013376 10561792 19790704
23:14:12 7501 8192000000 10050516992 10399838208 1131952816 12.140 100 80 160 0 1162857216 11658192 19246208
23:15:25 3559 8192000000 10098246400 10457174016 1180420032 12.594 100 85 167 1 1212621728 13185664 19016032
23:16:32 3229 8192000000 10143649072 10514821120 1231558160 13.068 100 80 158 1 1261702240 13046864 17097216
23:17:37 1527 8192000000 10174912528 10552725504 1261984720 13.348 100 85 170 0 1293514176 12941920 18587536
23:18:41 1264 8192000000 10206199872 10585755648 1287244352 13.579 100 94 188 1 1322841760 14496784 21100624
23:19:44 16852 8192000000 10183796528 10600521728 1299945248 13.695 100 47 94 1 1321072864 10736656 10390960
23:21:19 13312 8192000000 10197785360 10615595008 1315079824 13.832 100 12 24 0 1323968864 5886560 3002480
23:22:33 11042 8192000000 10203733328 10624831488 1323263264 13.906 100 10 18 0 1330532496 5418240 1850992
23:23:45 4575 8192000000 10225913664 10640531456 1327842160 13.948 100 33 67 0 1344813808 9931408 7040240
23:24:55 67376 8192000000 10214519648 10647527424 1336153152 14.023 100 26 52 1 1347273856 5210080 5910624
23:27:03 5735 8192000000 10223405408 10646794240 1336295072 14.024 100 33 66 0 1350489248 6593856 7600320
23:28:09 6897 8192000000 10190691328 10605170688 1296271472 13.661 99 14 27 0 1305026832 5679056 3076304
23:29:19 48798 8192000000 10126077856 10519891968 1148702688 12.297 90 25 49 0 1159368768 4749840 5916240
23:31:08 3529 8192000000 10137444304 10536906752 1243626208 13.180 100 38 73 0 1259531568 8231760 7673600
23:32:14 7070 8192000000 10146075088 10541916160 1236377936 13.113 99 26 53 0 1247813568 5294048 6141584
23:33:21 8468 8192000000 10170848944 10577735680 1275946256 13.476 100 14 29 0 1288538176 9599600 2992320
23:34:30 37909 8192000000 10167479136 10582675456 1281345584 13.525 100 36 71 0 1296038464 5907744 8785136
23:36:10 8851 8192000000 10185420048 10600599552 1297404144 13.672 100 19 38 1 1307308704 5758736 4145824
23:37:23 7922 8192000000 10196008720 10612269056 1306998416 13.759 100 16 32 0 1315906304 5686144 3221744
23:38:38 57047 8192000000 10242809744 10649219072 1333914208 14.003 100 36 72 1 1348899392 5898704 9086480
23:40:51 64811 8192000000 10256090608 10678824960 1353723792 14.181 100 47 93 1 1376059408 12620752 9714864
23:43:03 11443 8192000000 10280941472 10698498048 1371829680 14.343 100 43 86 0 1394403440 12816928 9756832
23:44:23 15888 8192000000 10293801504 10717954048 1382132592 14.436 100 36 71 1 1407480944 16437744 8910608
23:45:44 5708 8192000000 10307047520 10731290624 1393026176 14.533 100 53 108 1 1420735744 15212848 12496720
23:46:55 3860 8192000000 10303648096 10724450304 1375405120 14.375 98 47 94 3 1403570928 16788544 11377264
23:48:06 8269 8192000000 10313932704 10741325824 1399379616 14.589 100 45 90 1 1432654864 22394640 10880608
23:49:18 2479 8192000000 10326515152 10745344000 1398163568 14.579 100 62 122 0 1437423760 24141184 15119008
23:50:24 4661 8192000000 10320821568 10732904448 1377878000 14.398 99 57 110 0 1425861856 34494400 13489456
23:51:32 2399 8192000000 10320863936 10730995712 1380462960 14.421 100 64 130 1 1431207792 34517872 16226960
23:52:39 3188 8192000000 10334853488 10747756544 1389327904 14.500 100 57 114 3 1441849136 37712352 14808880
23:53:47 4139 8192000000 10335947136 10742173696 1375185280 14.373 99 79 158 0 1438919312 43303312 20430720
23:54:56 3839 8192000000 10335946384 10749460480 1388587744 14.493 100 52 103 1 1445036528 42619088 13829696
23:56:06 2741 8192000000 10340699600 10746355712 1377543744 14.395 100 65 130 1 1444814016 50108512 17161760
23:57:14 3691 8192000000 10363553696 10769268736 1391166944 14.516 100 79 158 0 1465058144 53311952 20579248
23:58:23 5514 8192000000 10343784256 10764877824 1381673904 14.432 100 54 106 0 1454219552 58290464 14255184
23:59:38 11726 8192000000 10322994768 10749046784 1368733520 14.316 99 38 76 1 1427497936 48364208 10400208
00:01:03 1960 8192000000 10329065376 10742800384 1358474864 14.224 100 68 134 2 1432642240 56278752 17888624
00:02:07 13642 8192000000 10313363584 10750390272 1359452912 14.232 100 24 48 1 1422192256 55534368 7204976
00:03:23 19708 8192000000 10311099872 10753449984 1370616384 14.333 100 30 61 0 1425381120 46033680 8731056
00:04:47 26865 8192000000 10282606736 10738896896 1358365696 14.223 99 15 30 0 1390620112 28180880 4073536
00:06:17 49452 8192000000 10282302944 10721079296 1359043696 14.229 99 25 46 3 1376913728 11984016 5886016
00:08:08 30849 8192000000 10283748992 10720088064 1362701392 14.262 99 24 49 0 1382752688 14611488 5439808
00:09:40 71491 8192000000 10291701376 10736742400 1342909856 14.084 97 14 27 0 1357538816 11424096 3204864
00:11:54 93829 8192000000 10301161328 10743939072 1384586384 14.458 100 11 22 0 1401088592 14037888 2464320
00:15:49 25370 8192000000 10323930768 10761859072 1400413488 14.599 100 31 61 0 1421105104 13678048 7013568
00:17:42 53086 8192000000 10323154160 10760908800 1388659952 14.494 99 47 93 0 1409468576 10301328 10507296
00:19:35 100774 8192000000 10330735024 10781310976 1380884080 14.424 98 28 57 0 1401110560 13751616 6474864
00:22:18 46047 8192000000 10320588640 10767609856 1393585520 14.538 99 22 45 0 1414870016 15794272 5490224
00:24:05 49704 8192000000 10296685888 10763423744 1385363280 14.464 99 20 38 0 1408019040 17989744 4666016
00:26:04 40106 8192000000 10295272368 10760302592 1381044768 14.426 99 12 24 0 1396523104 12657216 2821120
00:27:45 35447 8192000000 10292730752 10759897088 1394573184 14.547 100 9 18 0 1411493328 14679168 2240976
00:29:21 76472 8192000000 10300173568 10767458304 1395118096 14.552 100 11 22 0 1412078048 14710272 2249680
00:31:38 85129 8192000000 10300345488 10771877888 1404114320 14.632 100 14 28 0 1417539872 9867440 3558112
00:34:15 65932 8192000000 10310518128 10782932992 1385500560 14.466 98 13 24 0 1398826832 10942272 2384000
00:36:22 29373 8192000000 10292607232 10738515968 1332210720 13.987 95 22 43 0 1345736848 8642800 4883328
00:37:55 61824 8192000000 10299294640 10753613824 1357787824 14.217 97 19 38 0 1380120976 17866912 4466240
00:39:57 86841 8192000000 10291902128 10754760704 1350789568 14.155 97 19 38 1 1383756880 28183104 4784208
00:42:26 21925 8192000000 10266880208 10715062272 1334012320 14.003 97 15 30 0 1344275056 7109952 3152784
00:43:51 52560 8192000000 10255658512 10718101504 1319372336 13.871 97 43 86 1 1345651552 16949504 9329712
00:45:48 45425 8192000000 10262676992 10704814080 1315081712 13.832 97 21 42 1 1328684560 8953664 4649184
00:47:34 15300 8192000000 10277889744 10712784896 1328108656 13.950 97 44 85 0 1348636400 10727184 9800560
00:48:52 39662 8192000000 10259427120 10699874304 1312907280 13.812 96 29 58 1 1329883040 10169584 6806176
00:50:32 68815 8192000000 10273794320 10713874432 1348111888 14.130 99 31 62 2 1370718304 15631664 6974752
00:53:01 21684 8192000000 10261178176 10714234880 1354787648 14.191 100 24 48 0 1372999200 12584304 5627248
00:54:25 55838 8192000000 10266930048 10728472576 1366378592 14.295 100 7 14 0 1383312368 15356224 1577552
00:57:05 76827 8192000000 10278916704 10738855936 1361605072 14.252 99 16 32 0 1376363440 10811056 3947312
00:59:46 65044 8192000000 10277724976 10745774080 1378243072 14.401 100 19 38 0 1396960224 13964768 4752384
01:01:53 55075 8192000000 10282548688 10743918592 1382478480 14.439 100 15 30 0 1395514928 9396352 3640096
01:03:49 73033 8192000000 10283002864 10738864128 1359453008 14.232 98 13 26 0 1375319344 12501888 3364448
01:06:03 40785 8192000000 10298622160 10757476352 1392509728 14.528 100 31 60 0 1409205888 10232640 6463520
01:07:44 46981 8192000000 10312124128 10770587648 1402980224 14.622 100 35 68 0 1422790320 12440368 7369728
01:09:31 71971 8192000000 10300243568 10765819904 1362040272 14.256 97 16 32 0 1374983296 9646992 3296032
01:11:44 44519 8192000000 10310906272 10762510336 1382859280 14.442 99 18 36 0 1395635488 9164080 3612128
01:13:35 71265 8192000000 10307591392 10768044032 1373772912 14.361 98 35 70 1 1391660496 9978544 7909040
01:15:54 137228 8192000000 10307065360 10778021888 1390128000 14.507 99 23 46 0 1416586096 21037072 5421024
01:19:16 57024 8192000000 10287517760 10762989568 1367797600 14.307 97 16 32 0 1383145408 11583600 3764208
01:21:25 58723 8192000000 10303161376 10761662464 1390593792 14.511 99 17 33 0 1401014688 6698016 3722880
01:23:25 47568 8192000000 10302859888 10768527360 1393422288 14.536 99 7 14 0 1405534192 10252064 1859840
01:25:16 64573 8192000000 10260036272 10712551424 1316922528 13.849 95 25 48 1 1329846720 7019776 5904416
01:28:18 54435 8192000000 10258171664 10710249472 1295990448 13.659 94 16 32 0 1310773392 11196368 3586576
01:30:23 74840 8192000000 10265641808 10723491840 1339795792 14.056 98 26 52 0 1357326832 11753408 5777632
01:32:45 72956 8192000000 10284533120 10741850112 1369834080 14.326 99 18 36 0 1389633632 15041184 4758368
01:34:58 61598 8192000000 10299173024 10751451136 1375089424 14.373 99 25 50 1 1397427328 16629216 5708688
01:37:14 80165 8192000000 9494921504 9817931776 618373776 7.018 45 9 18 0 627962000 7805664 1782560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2022 01:39:32 to 05-10-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 61 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2022 01:39:48 to 05-10-2022 01:39:50.

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

05.10.22 01:39:52. 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-10-05 01:39:54 No severe PSQL-related problems occured
05.10.22 01:39:56. End of report.