Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15595560 kB
MemAvailable 30644116 kB
Buffers 0 kB
Cached 16066712 kB
SwapCached 3788 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.8
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 2021-10-14 01:38:09
DTS_END                         2021-10-14 01:37:57.1160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56724
TRN_ID                          239289
ATT_ID                          274
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: 9632.40 22425 1155888
customer order: creation 171.45 345 20574
customer order: refuse 14.39 379 1727
order to supplier: creation 45.13 1316 5415
order to supplier: removal 3.59 110751 431
invoice (draft): creation 45.43 3552 5452
invoice (draft): removal 56.38 905 6766
invoice accept: apply 41.66 17433 4999
invoice accept: cancel 51.05 815 6126
customer reserve: creation 3885.48 169 466257
customer reserve: removal 5014.28 60 601714
realization accept: apply 84.02 209 10082
realization accept: cancel 92.93 117 11151
payment from customer: creation 57.43 144 6891
payment from customer: removal 5.42 84 650
payment to supplier: creation 48.44 152 5813
payment to supplier: removal 3.31 50 397
service: total inventory turnovers 4.88 1121 585
service: total monetary turnovers 6.44 66 773
service: aggregate perf. data 0.68 29987 81
service: refresh index statistics 0.03 280853 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-10-2021 01:38:46 to 14-10-2021 01:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2318
TEST_TIME 2 2723
TEST_TIME 3 3416
TEST_TIME 4 2934
TEST_TIME 5 3505
TEST_TIME 6 4239
TEST_TIME 7 4327
TEST_TIME 8 4727
TEST_TIME 9 5332
TEST_TIME 10 5298
TEST_TIME 11 5633
TEST_TIME 12 5737
TEST_TIME 13 5753
TEST_TIME 14 6047
TEST_TIME 15 6363
TEST_TIME 16 6710
TEST_TIME 17 6762
TEST_TIME 18 6961
TEST_TIME 19 7099
TEST_TIME 20 7536
TEST_TIME 21 7677
TEST_TIME 22 7743
TEST_TIME 23 7881
TEST_TIME 24 8191
TEST_TIME 25 8573
TEST_TIME 26 8669
TEST_TIME 27 9188
TEST_TIME 28 8895
TEST_TIME 29 9232
TEST_TIME 30 10202
TEST_TIME 31 10655
TEST_TIME 32 10753
TEST_TIME 33 10654
TEST_TIME 34 10486
TEST_TIME 35 10265
TEST_TIME 36 10356
TEST_TIME 37 10575
TEST_TIME 38 10464
TEST_TIME 39 10398
TEST_TIME 40 10226
TEST_TIME 41 10331
TEST_TIME 42 10218
TEST_TIME 43 10092
TEST_TIME 44 9939
TEST_TIME 45 9881
TEST_TIME 46 10003
TEST_TIME 47 9837
TEST_TIME 48 9883
TEST_TIME 49 9832
TEST_TIME 50 9742
TEST_TIME 51 9874
TEST_TIME 52 9829
TEST_TIME 53 9727
TEST_TIME 54 9706
TEST_TIME 55 9717
TEST_TIME 56 9670
TEST_TIME 57 9705
TEST_TIME 58 9665
TEST_TIME 59 9642
TEST_TIME 60 9629
TEST_TIME 61 9643
TEST_TIME 62 9671
TEST_TIME 63 9654
TEST_TIME 64 9752
TEST_TIME 65 9770
TEST_TIME 66 9749
TEST_TIME 67 9793
TEST_TIME 68 9825
TEST_TIME 69 9891
TEST_TIME 70 9863
TEST_TIME 71 9885
TEST_TIME 72 9971
TEST_TIME 73 9963
TEST_TIME 74 9939
TEST_TIME 75 9862
TEST_TIME 76 9780
TEST_TIME 77 9753
TEST_TIME 78 9702
TEST_TIME 79 9679
TEST_TIME 80 9655
TEST_TIME 81 9687
TEST_TIME 82 9617
TEST_TIME 83 9608
TEST_TIME 84 9603
TEST_TIME 85 9537
TEST_TIME 86 9533
TEST_TIME 87 9494
TEST_TIME 88 9490
TEST_TIME 89 9444
TEST_TIME 90 9484
TEST_TIME 91 9516
TEST_TIME 92 9613
TEST_TIME 93 9604
TEST_TIME 94 9682
TEST_TIME 95 9674
TEST_TIME 96 9773
TEST_TIME 97 9783
TEST_TIME 98 9764
TEST_TIME 99 9817
TEST_TIME 100 9888
TEST_TIME 101 9877
TEST_TIME 102 9792
TEST_TIME 103 9774
TEST_TIME 104 9709
TEST_TIME 105 9739
TEST_TIME 106 9716
TEST_TIME 107 9677
TEST_TIME 108 9622
TEST_TIME 109 9650
TEST_TIME 110 9611
TEST_TIME 111 9553
TEST_TIME 112 9567
TEST_TIME 113 9541
TEST_TIME 114 9557
TEST_TIME 115 9579
TEST_TIME 116 9567
TEST_TIME 117 9573
TEST_TIME 118 9544
TEST_TIME 119 9543
TEST_TIME 120 9637

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2021 01:38:50 to 14-10-2021 01:38:53.

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 1587814 1587814 0 0.00 0 16056 6 0 0
doc_list_biud 1587814 1587814 0 0.00 0 35468 29 0 0
sp_add_invoice_to_stock 5117 4999 118 2.30 22 183105 17433 0 118
sp_cancel_adding_invoice 6126 6126 0 0.00 5 61945 816 0 0
sp_cancel_client_order 1727 1727 0 0.00 4 9527 379 0 0
sp_cancel_customer_reserve 601714 601714 0 0.00 1 28972 60 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 4415 84 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 968 51 0 0
sp_cancel_supplier_invoice 6776 6766 10 0.14 2 25963 905 0 10
sp_cancel_supplier_order 431 431 0 0.00 7 1161651 110752 0 0
sp_cancel_write_off 11151 11151 0 0.00 1 20873 118 0 0
sp_client_order 20574 20574 0 0.00 3 40084 346 0 0
sp_customer_reserve 466257 466257 0 0.00 0 89743 170 0 0
sp_fill_shopping_cart 31431 31431 0 0.00 1 13585 44 0 0
sp_fill_shopping_cart_clo_res 466252 466252 0 0.00 0 24203 12 0 0
sp_get_clo_for_invoice 4994 4994 0 0.00 0 23549 281 0 0
sp_kill_cost_storno 18324 18324 0 0.00 0 16054 63 0 0
sp_kill_qstorno_ret_qs2qd 1221776 1221776 0 0.00 0 28967 33 0 0
sp_kill_qty_storno 642991 642991 0 0.00 0 35466 72 0 0
sp_lock_selected_doc 20695 20695 0 0.00 0 4795 3 0 0
sp_make_cost_storno 27780 27780 0 0.00 0 14553 65 0 0
sp_multiply_rows_for_pdistr 27780 27780 0 0.00 0 8776 35 0 0
sp_multiply_rows_for_qdistr 20574 20574 0 0.00 0 11388 144 0 0
sp_pay_from_customer 6891 6891 0 0.00 4 27154 144 0 0
sp_pay_to_supplier 5813 5813 0 0.00 5 13362 153 0 0
sp_payment_common 12704 12704 0 0.00 1 13821 37 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 8658 327 0 0
sp_qd_handle_on_invoice_upd_sts 11120 11120 0 0.00 0 35466 375 0 0
sp_qd_handle_on_reserve_upd_sts 10082 10082 0 0.00 0 10400 53 0 0
sp_reserve_write_off 10082 10082 0 0.00 7 19678 209 0 0
sp_supplier_invoice 5452 5452 0 0.00 7 101706 3552 0 0
sp_supplier_order 5415 5415 0 0.00 5 33790 1316 0 0
srv_aggregate_perf_data 81 81 0 0.00 3104 609146 29988 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1960 149092 42855 0 0
srv_find_qd_qs_mism 1108531 1108531 0 0.00 0 11748 4 0 0
srv_make_invnt_saldo 585 585 0 0.00 60 62513 1122 0 0
srv_make_money_saldo 773 773 0 0.00 1 22599 67 0 0
srv_recalc_idx_stat 4 4 0 0.00 226477 388111 280854 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5000 5000 0 0.00 0 11339 175 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 2905 80 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 2470 14 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1242 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 413 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 562 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2430 17 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 23681 1793 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 1091 4 0 0
t$perf-norm:sp_client_order 20574 20574 0 0.00 0 11376 18 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 1097 6 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 12252 12 0 0
t$perf-norm:sp_pay_to_supplier 5813 5813 0 0.00 0 9108 13 0 0
t$perf-norm:sp_reserve_write_off 10082 10082 0 0.00 0 7641 11 0 0
t$perf-norm:sp_supplier_invoice 5452 5452 0 0.00 0 15222 20 0 0
t$perf-norm:sp_supplier_order 5415 5415 0 0.00 0 12269 19 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 412 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 4192 33 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 626 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 3 0 0
v_add_invoice_to_stock 10224 9988 236 2.30 0 20183 34 0 236
v_all_customers 37302 37302 0 0.00 0 16 1 0 0
v_all_suppliers 30280 30280 0 0.00 0 131 1 0 0
v_all_wares 41148 41148 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2841 13 0 0
v_cancel_client_order 3454 3454 0 0.00 0 8958 20 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 4388 11 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 4232 12 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 91 2 0 0
v_cancel_supplier_invoice 806 786 20 2.48 0 6348 37 0 20
v_cancel_supplier_order 862 862 0 0.00 0 9678 22 0 0
v_cancel_write_off 1958 1958 0 0.00 0 6454 24 0 0
v_max_id_clo_ord 5413 5413 0 0.00 0 318 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 1626 10 0 0
v_max_id_ord_sup 5442 5442 0 0.00 0 6371 4 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 1067 4 0 0
v_max_non_paid_realizn 3663 3663 0 0.00 0 5473 5 0 0
v_min_id_clo_ord 5413 5413 0 0.00 0 5457 8 0 0
v_min_id_clo_res 978 978 0 0.00 0 5510 16 0 0
v_min_id_ord_sup 5442 5442 0 0.00 0 10596 19 0 0
v_min_non_paid_invoice 5813 5813 0 0.00 0 7620 25 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 13817 31 0 0
v_our_firm 10302 10302 0 0.00 0 17 1 0 0
v_reserve_write_off 20160 20160 0 0.00 0 18324 16 0 0
x_lock_depdocs_on_canc_invoice 12499 12499 0 0.00 0 27352 127 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 23932 407 0 0
x_make_qstorno_1000_1200 5415 5415 0 0.00 2 33783 1229 0 0
x_make_qstorno_1200_2000 5452 5452 0 0.00 3 101688 3392 0 0
x_make_qstorno_2100_3300 466257 466257 0 0.00 0 89648 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2021 01:38:54 to 14-10-2021 01:38:58.

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:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 32038 8192000000 9898854080 10207952896 977142064 10.656 100 85 170 0 1005493840 5916160 22435616
23:10:43 17956 8192000000 9940130512 10263502848 1023465152 11.105 100 89 178 0 1054890608 8416528 23008928
23:12:10 23158 8192000000 9983448000 10320334848 1065871120 11.513 100 77 154 1 1094053584 9164384 19018080
23:13:40 7588 8192000000 10048591536 10399666176 1132135632 12.141 100 79 157 1 1162281168 11566992 18578544
23:15:00 8072 8192000000 10100846528 10463518720 1183933216 12.627 100 79 156 1 1214213392 12898560 17381616
23:16:10 4455 8192000000 10138466288 10510192640 1222520928 12.985 100 79 156 2 1253652176 13569312 17561936
23:17:18 4421 8192000000 10162976480 10544181248 1260248448 13.332 100 73 144 0 1287737136 11826816 15661872
23:18:24 1556 8192000000 10193641808 10579447808 1282486496 13.536 100 91 182 3 1315655104 12552384 20616224
23:19:27 11708 8192000000 10210532720 10595315712 1293502496 13.636 100 88 177 3 1325495536 12304816 19688224
23:20:39 4193 8192000000 10205992512 10607304704 1305396864 13.744 100 46 92 0 1323969024 9028752 9543408
23:21:44 6699 8192000000 10199091232 10614521856 1311414400 13.799 100 27 54 0 1324591376 6933456 6243520
23:22:53 12850 8192000000 10215983008 10631290880 1324269696 13.915 100 13 26 2 1332893888 5987712 2636480
23:24:11 61323 8192000000 10233157904 10652110848 1333424656 13.998 100 52 102 2 1355773376 9812000 12536720
23:26:14 4689 8192000000 10227127136 10644455424 1332390880 13.989 100 26 50 0 1343769104 6111408 5266816
23:27:21 7627 8192000000 10208198944 10629099520 1301282768 13.707 98 13 26 1 1309189776 4937056 2969952
23:28:29 18643 8192000000 10217054464 10614345728 1268002304 13.403 96 25 50 1 1279842400 5428608 6411488
23:30:00 10341 8192000000 10164451696 10565959680 1220679328 12.968 95 19 38 1 1230862576 5845120 4338128
23:31:11 6863 8192000000 10134184448 10538704896 1212213424 12.890 96 24 48 1 1223270352 5256576 5800352
23:32:19 5917 8192000000 10131604960 10534109184 1230752704 13.061 99 27 54 0 1242733616 5808016 6172896
23:33:27 9493 8192000000 10171306560 10571345920 1273143808 13.450 100 14 28 0 1281820528 5222688 3454032
23:34:38 29959 8192000000 10166983904 10572832768 1264213824 13.369 99 37 74 2 1278738704 6004016 8520864
23:36:11 5775 8192000000 10178658080 10593820672 1291663568 13.619 100 31 61 1 1305355216 6496000 7195648
23:37:19 8563 8192000000 10195076720 10605678592 1298040672 13.677 100 23 46 0 1309086064 5217872 5827520
23:38:38 15918 8192000000 10232934416 10637488128 1325277712 13.924 100 52 104 1 1346250784 7985408 12987664
23:40:01 33675 8192000000 10251316000 10667380736 1347325792 14.123 100 45 89 1 1365972496 8685168 9961536
23:42:17 20707 8192000000 10262337488 10688077824 1360695216 14.244 100 40 79 0 1380474816 10559248 9220352
23:43:41 53156 8192000000 10275026464 10696970240 1352229280 14.168 99 34 67 2 1372776656 12616464 7930912
23:45:37 15912 8192000000 10293906272 10714468352 1375391520 14.375 100 37 74 2 1401504144 17056528 9056096
23:46:56 8313 8192000000 10308263344 10730176512 1385977952 14.470 100 58 115 0 1419233456 20346416 12909088
23:48:11 3916 8192000000 10340045168 10758197248 1405477616 14.644 100 66 132 0 1447393536 26735152 15180768
23:49:29 4495 8192000000 10353890592 10769981440 1414557664 14.724 100 75 150 1 1459696304 27661248 17477392
23:50:38 4927 8192000000 10352949520 10766127104 1409881520 14.683 100 73 145 1 1458880416 31986448 17012448
23:51:47 2596 8192000000 10346082320 10751016960 1378563040 14.404 99 91 182 1 1438401840 37749184 22089616
23:52:54 4426 8192000000 10346822064 10755600384 1380409776 14.420 99 73 146 1 1441766976 43193696 18163504
23:54:02 2969 8192000000 10366159376 10772914176 1398077920 14.578 100 81 162 1 1467823344 48930800 20814624
23:55:09 3067 8192000000 10382178592 10788052992 1412709920 14.708 100 90 180 1 1485499760 50510016 22279824
23:56:17 3424 8192000000 10402810736 10809110528 1423649008 14.805 100 81 162 1 1502966960 59345328 19972624
23:57:26 1959 8192000000 10400458832 10808651776 1418545664 14.760 100 87 170 3 1504286624 62654832 23086128
23:58:33 5064 8192000000 10389665472 10808569856 1418994304 14.764 100 58 115 2 1492690720 58063760 15632656
23:59:44 3532 8192000000 10389493136 10811211776 1416080064 14.738 100 63 126 0 1496094864 63080336 16934464
00:00:54 5666 8192000000 10365631312 10785705984 1390330368 14.509 100 61 121 5 1475057696 67573440 17153888
00:02:11 13636 8192000000 10358699968 10794893312 1391066208 14.515 100 50 100 0 1472328736 67196176 14066352
00:03:34 9414 8192000000 10340761200 10767458304 1360123504 14.238 99 30 60 1 1433463968 64845664 8494800
00:04:46 15974 8192000000 10318094304 10756419584 1355193984 14.194 99 26 52 2 1413770560 50829520 7747056
00:06:02 10362 8192000000 10319446560 10745896960 1338395856 14.043 99 29 58 1 1414517952 67268384 8853712
00:07:15 39917 8192000000 10298618368 10733531136 1338884000 14.047 99 18 36 0 1384997712 41132000 4981712
00:09:03 96185 8192000000 10252956912 10696232960 1278480480 13.499 94 9 18 1 1293687680 13606704 1600496
00:11:43 38422 8192000000 10289019776 10727358464 1370422864 14.331 100 23 46 1 1390447568 14652800 5371904
00:13:39 94227 8192000000 10285854400 10725298176 1331072736 13.977 97 15 30 1 1357973312 23570224 3330352
00:16:18 50981 8192000000 10295310240 10737795072 1374271360 14.365 100 17 34 0 1388655632 10753280 3630992
00:18:24 78453 8192000000 10284843280 10720399360 1336822640 14.029 97 15 30 1 1349622688 9359152 3440896
00:20:50 149092 8192000000 10321390928 10760773632 1383284080 14.446 100 30 60 0 1405160080 14854176 7021824
00:24:29 43563 8192000000 10309979648 10756014080 1390879792 14.514 100 37 74 4 1410224304 11121424 8223088
00:26:14 47491 8192000000 10295860192 10742226944 1371887664 14.344 99 19 38 0 1383610960 7238784 4484512
00:28:01 6904 8192000000 10308172992 10748203008 1383912688 14.452 100 59 119 1 1409877504 12513984 13450832
00:29:12 13154 8192000000 10261019616 10725826560 1340126560 14.059 98 6 12 1 1349903328 8567536 1209232
00:30:26 41879 8192000000 10259340256 10712920064 1344380816 14.097 98 14 28 2 1357102512 9113376 3608320
00:32:15 35585 8192000000 10280919408 10736357376 1355214928 14.194 99 8 16 0 1371905312 14526448 2163936
00:33:51 70488 8192000000 10277674048 10740662272 1353944112 14.183 99 20 38 2 1371428240 13044816 4439312
00:36:03 40437 8192000000 10280538704 10745552896 1367485872 14.305 99 13 26 3 1387172000 16505392 3180736
00:37:44 47417 8192000000 10284862880 10754056192 1374312000 14.366 99 12 24 0 1395159152 18092816 2754336
00:39:32 45327 8192000000 10285591360 10725310464 1303475632 13.727 94 39 78 1 1323209040 10701648 9031760
00:41:18 55573 8192000000 10277964800 10734239744 1350567120 14.153 99 13 26 0 1364479376 11017632 2894624
00:43:25 38518 8192000000 10310609904 10750672896 1365697968 14.288 99 38 76 1 1386137664 11595072 8844624
00:45:09 96875 8192000000 10267001536 10720960512 1302907392 13.722 94 13 26 0 1323539968 17501968 3130608
00:47:48 57742 8192000000 10294396688 10742591488 1338821472 14.047 97 31 62 1 1361792144 15671648 7299024
00:49:57 106104 8192000000 10275065856 10729906176 1306505184 13.754 95 5 11 0 1325715840 18217600 993056
00:53:30 51358 8192000000 10271841760 10717401088 1323687584 13.910 97 31 58 4 1339765968 9142384 6936000
00:55:21 11486 8192000000 10294669856 10738741248 1368906832 14.317 100 55 111 2 1391810688 10933920 11969936
00:56:34 60996 8192000000 10278970304 10740252672 1368297600 14.312 100 21 41 0 1384477184 11384240 4795344
00:58:44 31491 8192000000 10290794848 10754588672 1375356576 14.375 100 13 27 0 1398195872 19858304 2980992
01:00:16 68506 8192000000 10280420896 10739351552 1361002320 14.246 99 19 38 0 1381101664 15368192 4731152
01:02:25 62881 8192000000 10272856912 10732666880 1361480560 14.251 99 21 40 3 1373667584 7453808 4733216
01:05:38 34534 8192000000 10256501104 10711932928 1326234080 13.933 97 12 24 0 1336914720 7571824 3108816
01:07:21 50934 8192000000 10275701760 10728878080 1352977184 14.174 99 23 46 2 1371227088 12946832 5303072
01:09:12 111924 8192000000 10285244416 10750889984 1329535488 13.963 97 7 14 0 1349312416 18520016 1256912
01:12:04 55857 8192000000 10303647200 10761109504 1382943728 14.443 100 16 32 0 1399335424 12812192 3579504
01:14:39 47056 8192000000 10299147936 10751688704 1370756832 14.334 99 17 34 0 1392259424 17502000 4000592
01:16:35 31680 8192000000 10293682224 10753032192 1353913856 14.183 98 42 82 0 1382481072 19360656 9206560
01:18:39 120435 8192000000 10301637600 10754818048 1382199632 14.436 100 22 44 2 1403583088 16117248 5266208
01:21:53 61759 8192000000 10276450672 10740436992 1352420288 14.169 98 25 50 1 1378694480 20505840 5768352
01:23:55 85856 8192000000 10254405136 10716823552 1303269504 13.725 95 11 22 0 1314726400 9027920 2428976
01:26:21 55656 8192000000 10258681920 10726825984 1359971424 14.237 100 14 28 0 1375275776 11801184 3503168
01:28:18 65281 8192000000 10269078064 10730094592 1353493520 14.179 99 15 31 0 1368517728 11579808 3444400
01:31:47 78883 8192000000 10253378000 10706145280 1284250224 13.552 94 16 32 1 1303174624 15274096 3650304
01:34:06 54836 8192000000 10277346368 10735710208 1328338784 13.952 97 23 46 0 1343098256 9785472 4974000
01:36:05 63733 8192000000 10281819952 10736779264 1351613680 14.162 99 23 46 1 1378421792 21110064 5698048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2021 01:39:00 to 14-10-2021 01:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2021 01:39:16 to 14-10-2021 01:39:18.

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          248460
MON$OLDEST_ACTIVE               248461
MON$OLDEST_SNAPSHOT             248461
MON$NEXT_TRANSACTION            248461
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                       2063904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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'

14.10.21 01:39:19. 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
2021-10-14 01:39:21 No severe PSQL-related problems occured
14.10.21 01:39:23. End of report.