Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.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 15086024 kB
MemAvailable 30043324 kB
Buffers 0 kB
Cached 16607088 kB
SwapCached 3452 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-12-19 22:38:38
DTS_END                         2022-12-19 22:38:38.1670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44340
TRN_ID                          252629
ATT_ID                          667
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: 9595.82 20520 1151498
customer order: creation 190.08 343 22810
customer order: refuse 16.24 307 1949
order to supplier: creation 49.45 1178 5934
order to supplier: removal 3.76 77399 451
invoice (draft): creation 50.30 3044 6036
invoice (draft): removal 51.65 653 6198
invoice accept: apply 45.21 18397 5425
invoice accept: cancel 46.53 793 5584
customer reserve: creation 4189.50 183 502740
customer reserve: removal 4638.28 50 556594
realization accept: apply 92.11 142 11053
realization accept: cancel 80.90 103 9708
payment from customer: creation 65.79 93 7895
payment from customer: removal 5.55 68 666
payment to supplier: creation 53.23 99 6387
payment to supplier: removal 4.18 77 502
service: total inventory turnovers 5.53 894 664
service: total monetary turnovers 6.68 30 802
service: aggregate perf. data 0.80 21663 96
service: refresh index statistics 0.03 284875 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2022 22:39:22 to 19-12-2022 22:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2155
TEST_TIME 2 1669
TEST_TIME 3 2886
TEST_TIME 4 3714
TEST_TIME 5 4471
TEST_TIME 6 4397
TEST_TIME 7 5033
TEST_TIME 8 5027
TEST_TIME 9 5377
TEST_TIME 10 5718
TEST_TIME 11 6139
TEST_TIME 12 6410
TEST_TIME 13 6738
TEST_TIME 14 6730
TEST_TIME 15 6995
TEST_TIME 16 7274
TEST_TIME 17 7624
TEST_TIME 18 7799
TEST_TIME 19 8382
TEST_TIME 20 8936
TEST_TIME 21 9133
TEST_TIME 22 9233
TEST_TIME 23 9589
TEST_TIME 24 10041
TEST_TIME 25 10618
TEST_TIME 26 10457
TEST_TIME 27 10510
TEST_TIME 28 10367
TEST_TIME 29 10457
TEST_TIME 30 10630
TEST_TIME 31 10475
TEST_TIME 32 10281
TEST_TIME 33 10114
TEST_TIME 34 10057
TEST_TIME 35 9974
TEST_TIME 36 10049
TEST_TIME 37 10006
TEST_TIME 38 10099
TEST_TIME 39 9949
TEST_TIME 40 9827
TEST_TIME 41 9768
TEST_TIME 42 9618
TEST_TIME 43 9466
TEST_TIME 44 9874
TEST_TIME 45 9872
TEST_TIME 46 9822
TEST_TIME 47 9922
TEST_TIME 48 9970
TEST_TIME 49 9938
TEST_TIME 50 9922
TEST_TIME 51 10054
TEST_TIME 52 10069
TEST_TIME 53 10039
TEST_TIME 54 10064
TEST_TIME 55 10084
TEST_TIME 56 10080
TEST_TIME 57 10060
TEST_TIME 58 9914
TEST_TIME 59 9814
TEST_TIME 60 9846
TEST_TIME 61 9820
TEST_TIME 62 9877
TEST_TIME 63 9798
TEST_TIME 64 9726
TEST_TIME 65 9708
TEST_TIME 66 9806
TEST_TIME 67 9755
TEST_TIME 68 9783
TEST_TIME 69 9803
TEST_TIME 70 9838
TEST_TIME 71 9790
TEST_TIME 72 9786
TEST_TIME 73 9833
TEST_TIME 74 9768
TEST_TIME 75 9930
TEST_TIME 76 9903
TEST_TIME 77 9923
TEST_TIME 78 9937
TEST_TIME 79 9888
TEST_TIME 80 9824
TEST_TIME 81 9739
TEST_TIME 82 9736
TEST_TIME 83 9692
TEST_TIME 84 9654
TEST_TIME 85 9661
TEST_TIME 86 9602
TEST_TIME 87 9651
TEST_TIME 88 9599
TEST_TIME 89 9664
TEST_TIME 90 9664
TEST_TIME 91 9706
TEST_TIME 92 9679
TEST_TIME 93 9649
TEST_TIME 94 9689
TEST_TIME 95 9680
TEST_TIME 96 9683
TEST_TIME 97 9686
TEST_TIME 98 9715
TEST_TIME 99 9737
TEST_TIME 100 9733
TEST_TIME 101 9750
TEST_TIME 102 9674
TEST_TIME 103 9646
TEST_TIME 104 9610
TEST_TIME 105 9625
TEST_TIME 106 9566
TEST_TIME 107 9556
TEST_TIME 108 9522
TEST_TIME 109 9498
TEST_TIME 110 9532
TEST_TIME 111 9523
TEST_TIME 112 9526
TEST_TIME 113 9489
TEST_TIME 114 9521
TEST_TIME 115 9505
TEST_TIME 116 9554
TEST_TIME 117 9545
TEST_TIME 118 9561
TEST_TIME 119 9543
TEST_TIME 120 9599

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-12-2022 22:39:25 to 19-12-2022 22:39:29.

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 1617638 1617638 0 0.00 0 17110 7 0 0
doc_list_biud 1617633 1617633 0 0.00 0 42144 22 0 0
sp_add_invoice_to_stock 5555 5425 130 2.34 21 184487 18398 0 130
sp_cancel_adding_invoice 5584 5584 0 0.00 3 61933 794 0 0
sp_cancel_client_order 1949 1949 0 0.00 4 20371 307 0 0
sp_cancel_customer_reserve 556594 556594 0 0.00 1 36156 50 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 6946 68 0 0
sp_cancel_pay_to_supplier 502 502 0 0.00 3 4567 77 0 0
sp_cancel_supplier_invoice 6207 6198 9 0.14 2 21257 653 0 9
sp_cancel_supplier_order 451 451 0 0.00 6 1027312 77400 0 0
sp_cancel_write_off 9708 9708 0 0.00 1 22762 104 0 0
sp_client_order 22810 22810 0 0.00 3 24169 343 0 0
sp_customer_reserve 502740 502740 0 0.00 0 55591 184 0 0
sp_fill_shopping_cart 34776 34776 0 0.00 1 19615 38 0 0
sp_fill_shopping_cart_clo_res 502727 502727 0 0.00 0 15698 14 0 0
sp_get_clo_for_invoice 5410 5410 0 0.00 0 21130 263 0 0
sp_kill_cost_storno 16460 16460 0 0.00 0 13677 44 0 0
sp_kill_qstorno_ret_qs2qd 1129545 1129545 0 0.00 0 42140 27 0 0
sp_kill_qty_storno 596946 596946 0 0.00 0 42142 59 0 0
sp_lock_selected_doc 22752 22752 0 0.00 0 7657 6 0 0
sp_make_cost_storno 30745 30745 0 0.00 0 13913 44 0 0
sp_multiply_rows_for_pdistr 30742 30742 0 0.00 0 9641 24 0 0
sp_multiply_rows_for_qdistr 22810 22810 0 0.00 0 16298 150 0 0
sp_pay_from_customer 7895 7895 0 0.00 4 11403 94 0 0
sp_pay_to_supplier 6387 6387 0 0.00 3 13082 99 0 0
sp_payment_common 14280 14280 0 0.00 1 11400 23 0 0
sp_qd_handle_on_cancel_clo 1949 1949 0 0.00 0 20364 279 0 0
sp_qd_handle_on_invoice_upd_sts 10993 10993 0 0.00 0 31396 282 0 0
sp_qd_handle_on_reserve_upd_sts 11053 11053 0 0.00 0 19972 51 0 0
sp_reserve_write_off 11053 11053 0 0.00 5 19992 142 0 0
sp_supplier_invoice 6036 6036 0 0.00 5 87391 3045 0 0
sp_supplier_order 5934 5934 0 0.00 5 61932 1179 0 0
srv_aggregate_perf_data 96 96 0 0.00 3133 325035 21663 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1277 116291 43066 0 0
srv_find_qd_qs_mism 1099571 1099571 0 0.00 0 17109 7 0 0
srv_make_invnt_saldo 664 664 0 0.00 46 56844 895 0 0
srv_make_money_saldo 802 802 0 0.00 0 6681 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 204515 383729 284875 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5425 5425 0 0.00 0 6587 129 0 0
t$perf-norm:sp_cancel_adding_invoice 453 453 0 0.00 0 3035 83 0 0
t$perf-norm:sp_cancel_client_order 1949 1949 0 0.00 0 7240 20 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 3589 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 274 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 502 502 0 0.00 0 2468 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 701 10 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 15189 1192 0 0
t$perf-norm:sp_cancel_write_off 1128 1128 0 0.00 0 4837 13 0 0
t$perf-norm:sp_client_order 22810 22810 0 0.00 0 6244 14 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 1169 4 0 0
t$perf-norm:sp_pay_from_customer 7895 7895 0 0.00 0 5716 7 0 0
t$perf-norm:sp_pay_to_supplier 6387 6387 0 0.00 0 6125 8 0 0
t$perf-norm:sp_reserve_write_off 11053 11053 0 0.00 0 9883 9 0 0
t$perf-norm:sp_supplier_invoice 6036 6036 0 0.00 0 6251 13 0 0
t$perf-norm:sp_supplier_order 5934 5934 0 0.00 0 4165 12 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 242 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 28393 452 0 0
t$perf-norm:srv_make_invnt_saldo 664 664 0 0.00 0 603 17 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 618 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 11078 10818 260 2.34 0 8411 16 0 260
v_all_customers 41622 41622 0 0.00 0 78 1 0 0
v_all_suppliers 33302 33302 0 0.00 0 22 1 0 0
v_all_wares 45620 45620 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 906 906 0 0.00 0 630 6 0 0
v_cancel_client_order 3898 3898 0 0.00 0 4046 6 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 968 3 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 941 7 0 0
v_cancel_payment_to_supplier 1004 1004 0 0.00 0 2475 8 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 385 11 0 18
v_cancel_supplier_order 902 902 0 0.00 0 665 5 0 0
v_cancel_write_off 2256 2256 0 0.00 0 1211 13 0 0
v_max_id_clo_ord 5932 5932 0 0.00 0 6803 3 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 938 7 0 0
v_max_id_ord_sup 6034 6034 0 0.00 0 7496 7 0 0
v_max_non_paid_invoice 1701 1701 0 0.00 0 1122 4 0 0
v_max_non_paid_realizn 4227 4227 0 0.00 0 538 3 0 0
v_min_id_clo_ord 5932 5932 0 0.00 0 5701 13 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 12602 28 0 0
v_min_id_ord_sup 6034 6034 0 0.00 0 6836 9 0 0
v_min_non_paid_invoice 6386 6386 0 0.00 0 5232 12 0 0
v_min_non_paid_realizn 7894 7894 0 0.00 0 11393 20 0 0
v_our_firm 11332 11332 0 0.00 0 19 1 0 0
v_reserve_write_off 22106 22106 0 0.00 0 1860 5 0 0
x_lock_depdocs_on_canc_invoice 11388 11388 0 0.00 0 20293 125 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 10720 297 0 0
x_make_qstorno_1000_1200 5934 5934 0 0.00 1 61910 1105 0 0
x_make_qstorno_1200_2000 6036 6036 0 0.00 2 87383 2903 0 0
x_make_qstorno_2100_3300 502740 502740 0 0.00 0 55589 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2022 22:39:30 to 19-12-2022 22:39:34.

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:41 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:42 25942 8192000000 9898819472 10211717120 977605312 10.661 100 89 178 0 1007810784 6244944 23960528
20:11:14 21065 8192000000 9953053472 10278174720 1039297440 11.258 100 88 176 0 1070485904 7745904 23442560
20:12:44 10664 8192000000 10006427696 10339692544 1086970544 11.714 100 93 186 0 1119582080 9361552 23249984
20:14:01 11156 8192000000 10043872208 10390966272 1130759152 12.129 100 77 154 0 1159578352 10716400 18102800
20:15:14 3545 8192000000 10079400496 10440429568 1172713456 12.522 100 87 170 0 1205294640 12566720 20014464
20:16:22 3913 8192000000 10126972448 10500202496 1219831424 12.960 100 80 158 0 1254536304 17317168 17387712
20:17:30 3036 8192000000 10162752960 10539282432 1250318528 13.241 100 79 156 0 1280883968 13614560 16950880
20:18:37 2953 8192000000 10182942976 10561785856 1266817808 13.392 100 83 164 0 1298095888 12936368 18341712
20:19:42 1251 8192000000 10208272224 10592022528 1295511360 13.654 100 83 168 1 1326102816 12009632 18581824
20:20:45 27241 8192000000 10212993536 10608914432 1301452544 13.708 100 59 118 0 1326697280 10338848 14905888
20:22:13 5044 8192000000 10201961984 10609889280 1309134320 13.778 100 42 83 0 1326912272 8444272 9333680
20:23:19 6122 8192000000 10202047472 10618757120 1314995504 13.831 100 27 54 2 1327646432 7053968 5596960
20:24:26 7431 8192000000 10205185808 10622435328 1320191312 13.878 100 11 22 0 1328727040 6277568 2258160
20:25:39 39969 8192000000 10232189504 10639110144 1324787072 13.920 100 53 106 1 1344354016 5956864 13610080
20:27:19 5604 8192000000 10221469856 10641649664 1329208576 13.960 100 17 34 0 1339182880 6119808 3854496
20:28:30 8174 8192000000 10219667760 10642046976 1321658800 13.892 99 19 38 0 1330719680 5386496 3674384
20:29:38 43151 8192000000 10170066864 10583773184 1244241696 13.185 95 32 64 1 1257729808 6173424 7314688
20:31:25 9377 8192000000 10155412800 10558025728 1249228400 13.231 99 26 51 1 1260367008 5299792 5838816
20:32:35 8894 8192000000 10137180912 10526638080 1218059248 12.944 98 28 57 2 1230006560 4662112 7285200
20:33:44 47180 8192000000 10147378592 10546491392 1243231216 13.176 98 32 65 0 1255531936 4548704 7752016
20:35:38 7769 8192000000 10173926112 10586804224 1283279360 13.543 100 16 30 0 1292571744 5170224 4122160
20:36:47 5853 8192000000 10186835824 10597625856 1291005376 13.613 100 26 53 1 1302831104 6070672 5755056
20:37:53 10862 8192000000 10198427760 10609016832 1304276160 13.734 100 21 40 0 1313843072 5293136 4273776
20:39:11 29558 8192000000 10231230400 10645434368 1334129184 14.004 100 44 85 3 1350904032 6970128 9804720
20:40:43 93440 8192000000 10247598336 10672074752 1341945728 14.075 100 29 58 0 1360365392 11356960 7062704
20:43:21 15764 8192000000 10277899872 10693611520 1361932224 14.255 100 51 99 0 1386634528 13849568 10852736
20:44:39 16457 8192000000 10292137584 10711539712 1375929296 14.380 100 49 97 0 1402097104 14960608 11207200
20:46:00 13468 8192000000 10291072000 10719006720 1380288112 14.419 100 41 81 1 1405826576 15780176 9758288
20:47:17 23552 8192000000 10322977520 10744016896 1393730192 14.539 100 55 109 0 1428718880 21927616 13061072
20:48:44 8442 8192000000 10326595008 10754863104 1401135552 14.605 100 48 94 0 1438149856 24822512 12191792
20:49:59 2245 8192000000 10342416976 10755112960 1398744304 14.584 99 79 155 1 1443028304 26855296 17428704
20:51:04 4203 8192000000 10350395552 10772901888 1416043536 14.738 100 74 145 2 1460813904 27305920 17464448
20:52:13 1910 8192000000 10366726320 10785726464 1420691552 14.779 100 76 152 0 1467055424 27975296 18388576
20:53:19 5222 8192000000 10334679792 10751356928 1392130784 14.525 100 67 130 1 1439397680 30994352 16272544
20:54:30 1275 8192000000 10337899728 10745049088 1376443408 14.385 99 76 153 0 1433810928 37787744 19579776
20:55:36 2136 8192000000 10310495072 10716745728 1356119408 14.203 99 74 146 0 1412283296 36639856 19524032
20:56:44 5720 8192000000 10333953664 10750939136 1387005632 14.479 100 61 122 1 1442917072 40271008 15640432
20:57:53 11071 8192000000 10292550736 10715525120 1339498640 14.053 98 37 74 2 1387192608 37861136 9832832
20:59:13 10028 8192000000 10270196848 10696818688 1342443760 14.079 99 37 74 0 1381340656 29510448 9386448
21:00:24 42052 8192000000 10260557632 10692780032 1331579776 13.981 100 19 38 0 1370259376 33209488 5470112
21:02:07 28480 8192000000 10231881632 10669305856 1306250784 13.752 98 14 26 0 1335022832 25828800 2943248
21:04:25 56571 8192000000 10237050464 10664935424 1273605600 13.455 95 21 40 0 1285874992 7981872 4287520
21:06:29 57985 8192000000 10240231424 10671923200 1286990048 13.577 96 16 32 0 1306435744 15817536 3628160
21:08:28 81375 8192000000 10263658720 10707288064 1327931216 13.948 98 16 33 0 1343789376 12516224 3341936
21:11:24 15168 8192000000 10275148288 10713636864 1362306032 14.258 100 59 118 1 1384743552 7857456 14580064
21:12:46 25384 8192000000 10290718016 10724597760 1349120384 14.140 98 44 90 1 1366422704 7751008 9551312
21:14:18 70010 8192000000 10275355392 10742546432 1367218640 14.302 99 27 54 0 1389209120 15907376 6083104
21:16:29 63788 8192000000 10296227104 10754719744 1388350544 14.491 100 21 42 1 1403128384 10063680 4714160
21:19:24 79564 8192000000 10316133152 10775130112 1409835360 14.682 100 20 40 0 1428218368 13397168 4985840
21:22:12 28719 8192000000 10297241664 10771042304 1392949120 14.532 100 19 38 0 1414616672 16833584 4833968
21:24:06 55246 8192000000 10316697840 10767024128 1380708592 14.423 98 19 38 2 1397740416 12596688 4435136
21:26:04 51824 8192000000 10311918624 10777878528 1405973968 14.648 100 21 40 0 1419409392 9215632 4219792
21:27:59 15738 8192000000 10328166176 10780741632 1412527664 14.706 100 45 90 1 1433310400 10870064 9912672
21:29:16 78655 8192000000 10327220912 10795933696 1404283904 14.633 99 14 28 0 1421903584 14473424 3146256
21:31:38 38226 8192000000 10318163344 10778198016 1391310416 14.518 98 37 74 1 1410211328 9877072 9023840
21:33:20 102162 8192000000 10287920944 10740101120 1287873152 13.585 91 18 36 0 1301729856 10068592 3788112
21:36:02 116291 8192000000 10244818080 10686455808 1288519184 13.591 94 21 42 0 1303667056 10406464 4741408
21:39:43 45815 8192000000 10259783280 10723545088 1348014576 14.130 98 24 44 0 1366654016 13103568 5535872
21:41:37 47604 8192000000 10269059840 10725793792 1365321584 14.285 100 8 16 0 1374171136 7355184 1494368
21:43:29 41046 8192000000 10266907856 10716352512 1328259648 13.951 97 15 30 0 1344612400 13100320 3252432
21:45:11 21891 8192000000 10257381616 10699894784 1320443472 13.881 97 25 52 0 1333144832 7072096 5629264
21:46:33 10718 8192000000 10254173856 10684784640 1332971120 13.994 100 60 120 0 1354720640 7905776 13843744
21:47:45 68228 8192000000 10246278016 10689028096 1284357232 13.553 95 20 39 0 1298862352 10203744 4301376
21:50:02 12991 8192000000 10262152256 10685673472 1314258544 13.825 98 53 106 0 1334734864 8987728 11488592
21:51:20 50898 8192000000 10251741968 10684751872 1298874128 13.685 97 25 49 1 1314179184 9560560 5744496
21:53:12 74276 8192000000 10253525296 10713337856 1335663952 14.018 99 8 14 0 1357007008 19694032 1649024
21:55:56 91028 8192000000 10290998608 10735091712 1359083856 14.229 99 26 52 0 1375984560 11585120 5315584
21:58:30 85355 8192000000 10273309312 10728472576 1370210672 14.329 99 22 44 0 1383952096 8567456 5173968
22:00:56 40775 8192000000 10286309776 10751438848 1390774880 14.513 100 14 28 0 1402237648 8130288 3332480
22:02:38 62842 8192000000 10293568448 10760605696 1382709728 14.441 99 13 24 0 1398704304 12757712 3236864
22:04:50 78279 8192000000 10300628368 10770501632 1385406208 14.465 99 15 30 0 1403706176 14510288 3789680
22:07:15 45715 8192000000 10305688704 10758975488 1394289440 14.544 100 19 38 1 1405698832 7206928 4202464
22:09:27 59224 8192000000 10318263712 10760540160 1385970960 14.470 98 14 28 0 1399115808 10164256 2980592
22:11:27 26747 8192000000 10322690736 10771218432 1387665808 14.485 98 39 78 0 1411969072 15746656 8556608
22:12:59 32414 8192000000 10317719968 10768211968 1399284448 14.589 99 28 56 1 1414563600 8779936 6499216
22:14:32 20143 8192000000 10321484688 10768224256 1390955216 14.514 99 36 72 0 1408900400 10294704 7650480
22:15:58 87843 8192000000 10313142224 10774261760 1372063312 14.346 98 31 62 0 1394482080 15671152 6747616
22:18:27 67896 8192000000 10302663600 10759405568 1365388272 14.286 98 20 40 3 1378929056 9401792 4138992
22:20:40 74909 8192000000 10294942128 10748882944 1356456736 14.206 97 22 44 0 1368146320 6264432 5425152
22:22:56 42110 8192000000 10263515536 10717011968 1343493280 14.089 97 26 51 0 1360858896 11995088 5370528
22:24:46 52959 8192000000 10216535936 10666303488 1282031248 13.532 94 14 28 0 1291965520 6655200 3279072
22:26:43 33900 8192000000 10240541776 10696065024 1326016640 13.931 98 28 56 0 1342153488 9881936 6254912
22:28:17 47504 8192000000 10240274160 10699829248 1315327088 13.834 98 7 14 0 1325265776 8478816 1459872
22:30:05 75643 8192000000 10233072128 10677866496 1261877296 13.347 94 16 32 0 1281363152 15539296 3946560
22:32:22 32719 8192000000 10256816496 10688651264 1312630080 13.810 98 33 65 0 1327955488 8033280 7292128
22:33:57 50188 8192000000 10232192928 10678312960 1298189456 13.679 97 24 48 0 1317053248 13155408 5708384
22:35:49 51259 8192000000 10224025024 10672979968 1291464048 13.618 98 20 38 0 1308552384 12431424 4656912
22:38:34 14598 8192000000 9006372240 9210171392 169597760 2.028 13 1 2 0 173944656 4304544 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2022 22:39:36 to 19-12-2022 22:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2022 22:39:52 to 19-12-2022 22:39:54.

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

19.12.22 22:39:55. 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-12-19 22:39:58 No severe PSQL-related problems occured
19.12.22 22:39:59. End of report.