Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.03.2023 10: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 15135640 kB
MemAvailable 30433936 kB
Buffers 0 kB
Cached 16591952 kB
SwapCached 3200 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-18 10:37:25
DTS_END                         2023-03-18 10:37:25.7270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34888
TRN_ID                          215456
ATT_ID                          670
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: 8644.74 29563 1037369
customer order: creation 158.23 445 18988
customer order: refuse 14.36 390 1723
order to supplier: creation 43.58 1395 5230
order to supplier: removal 3.39 171130 407
invoice (draft): creation 43.08 3066 5169
invoice (draft): removal 48.71 1064 5845
invoice accept: apply 39.22 27886 4706
invoice accept: cancel 44.44 1279 5333
customer reserve: creation 3618.28 269 434193
customer reserve: removal 4359.23 105 523107
realization accept: apply 77.59 299 9311
realization accept: cancel 72.94 168 8753
payment from customer: creation 55.14 185 6617
payment from customer: removal 5.48 136 657
payment to supplier: creation 45.49 198 5459
payment to supplier: removal 3.52 135 422
service: total inventory turnovers 3.82 3935 458
service: total monetary turnovers 7.14 352 857
service: aggregate perf. data 1.08 13353 130
service: refresh index statistics 0.03 365466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-03-2023 10:39:01 to 18-03-2023 10:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6631
TEST_TIME 2 5714
TEST_TIME 3 5010
TEST_TIME 4 5642
TEST_TIME 5 5336
TEST_TIME 6 5232
TEST_TIME 7 5950
TEST_TIME 8 6137
TEST_TIME 9 6169
TEST_TIME 10 6567
TEST_TIME 11 6561
TEST_TIME 12 6690
TEST_TIME 13 6799
TEST_TIME 14 7356
TEST_TIME 15 7253
TEST_TIME 16 7392
TEST_TIME 17 7578
TEST_TIME 18 7572
TEST_TIME 19 7718
TEST_TIME 20 7686
TEST_TIME 21 7921
TEST_TIME 22 7872
TEST_TIME 23 8351
TEST_TIME 24 8315
TEST_TIME 25 8229
TEST_TIME 26 8369
TEST_TIME 27 8648
TEST_TIME 28 9198
TEST_TIME 29 9599
TEST_TIME 30 9966
TEST_TIME 31 10462
TEST_TIME 32 11151
TEST_TIME 33 11207
TEST_TIME 34 11027
TEST_TIME 35 10907
TEST_TIME 36 10686
TEST_TIME 37 10587
TEST_TIME 38 10492
TEST_TIME 39 10235
TEST_TIME 40 10435
TEST_TIME 41 10298
TEST_TIME 42 10240
TEST_TIME 43 10020
TEST_TIME 44 9937
TEST_TIME 45 9783
TEST_TIME 46 9678
TEST_TIME 47 9593
TEST_TIME 48 9592
TEST_TIME 49 9592
TEST_TIME 50 9543
TEST_TIME 51 9655
TEST_TIME 52 9857
TEST_TIME 53 9785
TEST_TIME 54 9717
TEST_TIME 55 9674
TEST_TIME 56 9670
TEST_TIME 57 9618
TEST_TIME 58 9551
TEST_TIME 59 9519
TEST_TIME 60 9605
TEST_TIME 61 9566
TEST_TIME 62 9544
TEST_TIME 63 9413
TEST_TIME 64 9407
TEST_TIME 65 9305
TEST_TIME 66 9261
TEST_TIME 67 9238
TEST_TIME 68 9177
TEST_TIME 69 9120
TEST_TIME 70 9032
TEST_TIME 71 9021
TEST_TIME 72 9006
TEST_TIME 73 8964
TEST_TIME 74 8922
TEST_TIME 75 8950
TEST_TIME 76 8917
TEST_TIME 77 9021
TEST_TIME 78 9085
TEST_TIME 79 9100
TEST_TIME 80 9057
TEST_TIME 81 9098
TEST_TIME 82 9155
TEST_TIME 83 9136
TEST_TIME 84 9109
TEST_TIME 85 9170
TEST_TIME 86 9111
TEST_TIME 87 9049
TEST_TIME 88 9106
TEST_TIME 89 9080
TEST_TIME 90 9048
TEST_TIME 91 8993
TEST_TIME 92 8936
TEST_TIME 93 8867
TEST_TIME 94 8824
TEST_TIME 95 8783
TEST_TIME 96 8805
TEST_TIME 97 8772
TEST_TIME 98 8766
TEST_TIME 99 8755
TEST_TIME 100 8741
TEST_TIME 101 8713
TEST_TIME 102 8782
TEST_TIME 103 8795
TEST_TIME 104 8868
TEST_TIME 105 8903
TEST_TIME 106 8887
TEST_TIME 107 8945
TEST_TIME 108 8938
TEST_TIME 109 8921
TEST_TIME 110 8991
TEST_TIME 111 8950
TEST_TIME 112 8876
TEST_TIME 113 8852
TEST_TIME 114 8835
TEST_TIME 115 8791
TEST_TIME 116 8753
TEST_TIME 117 8747
TEST_TIME 118 8709
TEST_TIME 119 8715
TEST_TIME 120 8668

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-03-2023 10:39:05 to 18-03-2023 10:39:08.

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 1439907 1439907 0 0.00 0 21760 14 0 0
doc_list_biud 1439893 1439893 0 0.00 0 71499 44 0 0
sp_add_invoice_to_stock 4854 4706 148 3.04 69 256426 27887 0 148
sp_cancel_adding_invoice 5333 5333 0 0.00 7 96299 1280 0 0
sp_cancel_client_order 1723 1723 0 0.00 6 8351 391 0 0
sp_cancel_customer_reserve 523107 523107 0 0.00 1 21418 106 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 4 10921 136 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 4990 135 0 0
sp_cancel_supplier_invoice 5856 5845 11 0.18 3 71517 1065 0 11
sp_cancel_supplier_order 407 407 0 0.00 8 1554024 171130 0 0
sp_cancel_write_off 8753 8753 0 0.00 2 11176 169 0 0
sp_client_order 18988 18988 0 0.00 5 23997 446 0 0
sp_customer_reserve 434193 434193 0 0.00 0 99850 269 0 0
sp_fill_shopping_cart 29377 29377 0 0.00 1 13536 83 0 0
sp_fill_shopping_cart_clo_res 434160 434160 0 0.00 0 13239 19 0 0
sp_get_clo_for_invoice 4664 4664 0 0.00 0 24005 361 0 0
sp_kill_cost_storno 15165 15165 0 0.00 0 19671 74 0 0
sp_kill_qstorno_ret_qs2qd 1061219 1061219 0 0.00 0 71498 46 0 0
sp_kill_qty_storno 559143 559143 0 0.00 0 71499 105 0 0
sp_lock_selected_doc 19568 19568 0 0.00 0 8268 8 0 0
sp_make_cost_storno 26051 26051 0 0.00 0 21737 91 0 0
sp_multiply_rows_for_pdistr 26051 26051 0 0.00 0 9495 39 0 0
sp_multiply_rows_for_qdistr 18987 18987 0 0.00 0 23969 190 0 0
sp_pay_from_customer 6617 6617 0 0.00 5 14257 186 0 0
sp_pay_to_supplier 5459 5459 0 0.00 6 19223 199 0 0
sp_payment_common 12076 12076 0 0.00 2 13938 52 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 1 8342 316 0 0
sp_qd_handle_on_invoice_upd_sts 9997 9997 0 0.00 0 18521 530 0 0
sp_qd_handle_on_reserve_upd_sts 9311 9311 0 0.00 0 12630 65 0 0
sp_reserve_write_off 9311 9311 0 0.00 9 21515 300 0 0
sp_supplier_invoice 5169 5169 0 0.00 10 95941 3066 0 0
sp_supplier_order 5230 5230 0 0.00 8 29550 1396 0 0
srv_aggregate_perf_data 130 130 0 0.00 17 120466 13353 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 723 133675 36777 0 0
srv_find_qd_qs_mism 992961 992961 0 0.00 0 13447 11 0 0
srv_make_invnt_saldo 458 458 0 0.00 37 50341 3935 0 0
srv_make_money_saldo 857 857 0 0.00 1 12757 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 230520 465470 365467 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4709 4709 0 0.00 1 11067 944 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 11273 456 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 1444 25 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 866 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 756 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 242 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 642 33 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 77767 6334 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 3233 21 0 0
t$perf-norm:sp_client_order 18989 18989 0 0.00 0 49844 36 0 0
t$perf-norm:sp_customer_reserve 892 892 0 0.00 0 1346 15 0 0
t$perf-norm:sp_pay_from_customer 6617 6617 0 0.00 0 21589 24 0 0
t$perf-norm:sp_pay_to_supplier 5459 5459 0 0.00 0 5397 21 0 0
t$perf-norm:sp_reserve_write_off 9311 9311 0 0.00 0 4522 23 0 0
t$perf-norm:sp_supplier_invoice 5169 5169 0 0.00 0 4150 28 0 0
t$perf-norm:sp_supplier_order 5230 5230 0 0.00 0 6583 31 0 0
t$perf-norm:srv_aggregate_perf_data 130 130 0 0.00 0 759 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 37 3 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 1467 20 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 2366 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 177 52 0 0
v_add_invoice_to_stock 9618 9322 296 3.07 0 6798 28 0 296
v_all_customers 34556 34556 0 0.00 0 94 4 0 0
v_all_suppliers 28724 28724 0 0.00 0 68 4 0 0
v_all_wares 37964 37964 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 4878 20 0 0
v_cancel_client_order 3446 3446 0 0.00 0 4653 14 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 1494 13 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 1 2657 15 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 4836 23 0 0
v_cancel_supplier_invoice 822 800 22 2.67 0 291 21 0 22
v_cancel_supplier_order 814 814 0 0.00 0 1503 14 0 0
v_cancel_write_off 1880 1880 0 0.00 0 5420 30 0 0
v_max_id_clo_ord 5228 5228 0 0.00 0 997 5 0 0
v_max_id_clo_res 892 892 0 0.00 0 2743 19 0 0
v_max_id_ord_sup 5164 5164 0 0.00 0 1980 6 0 0
v_max_non_paid_invoice 1489 1489 0 0.00 0 521 8 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 7256 11 0 0
v_min_id_clo_ord 5228 5228 0 0.00 0 10140 11 0 0
v_min_id_clo_res 892 892 0 0.00 0 1766 9 0 0
v_min_id_ord_sup 5164 5164 0 0.00 0 4949 11 0 0
v_min_non_paid_invoice 5459 5459 0 0.00 0 4930 18 0 0
v_min_non_paid_realizn 6617 6617 0 0.00 0 13931 26 0 0
v_our_firm 9548 9548 0 0.00 0 63 5 0 0
v_reserve_write_off 18622 18622 0 0.00 0 5341 12 0 0
x_lock_depdocs_on_canc_invoice 10778 10778 0 0.00 0 21601 142 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 9436 359 0 0
x_make_qstorno_1000_1200 5230 5230 0 0.00 2 29533 1224 0 0
x_make_qstorno_1200_2000 5169 5169 0 0.00 4 95865 2848 0 0
x_make_qstorno_2100_3300 434192 434192 0 0.00 0 99843 238 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-03-2023 10:39:09 to 18-03-2023 10:39:13.

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
08:07:29 6 8192000000 8807828080 8822521856 11845536 0.144 4 3 4 0 12106800 174320 86944
08:08:30 29090 8192000000 9732597152 10032758784 812215536 9.020 100 94 188 0 843363216 8331632 22816048
08:10:15 29674 8192000000 9766295568 10077986816 837372720 9.273 100 90 180 1 872514096 13597072 21544304
08:11:49 16729 8192000000 9807871280 10129924096 867862704 9.579 100 88 176 0 909128752 20211408 21054640
08:13:12 5670 8192000000 9859835888 10191753216 912252224 10.020 100 78 151 1 974687744 46514384 15921136
08:14:20 3733 8192000000 9914371104 10257145856 953396416 10.424 100 90 174 0 1031055360 59260000 18398944
08:15:28 1928 8192000000 9969000080 10319020032 988473456 10.767 100 93 182 2 1077527360 70327440 18726464
08:16:33 1510 8192000000 9979067584 10339631104 1013744912 11.012 100 87 171 1 1096472752 65946544 16781296
08:17:36 886 8192000000 10002268464 10367586304 1033950896 11.206 100 95 182 0 1117159440 64783632 18424912
08:18:38 963 8192000000 10021889776 10396024832 1054582224 11.405 100 94 179 0 1142147760 69007440 18558096
08:19:40 1118 8192000000 10030489968 10407407616 1064777264 11.502 100 93 185 0 1155319104 71699440 18842400
08:20:42 8266 8192000000 10041754912 10430382080 1067477232 11.528 100 72 140 0 1168085376 87087248 13520896
08:21:52 922 8192000000 10027371264 10413375488 1074943600 11.599 100 88 162 3 1147706896 56131840 16631456
08:22:54 1344 8192000000 10067716848 10455404544 1079993296 11.647 100 97 185 0 1187786736 89462880 18330560
08:23:56 1162 8192000000 10073877072 10461044736 1086531424 11.710 100 94 180 2 1190696640 86100384 18064832
08:24:58 1216 8192000000 10066603728 10455396352 1094757360 11.788 100 98 189 0 1191095856 77316688 19021808
08:26:00 1220 8192000000 10061318928 10446909440 1095724688 11.797 100 96 186 2 1183543920 68032832 19786400
08:27:02 1088 8192000000 10051314544 10439024640 1092693136 11.768 100 94 184 2 1174705200 64056176 17955888
08:28:04 3085 8192000000 10061043632 10461925376 1091293696 11.755 100 70 134 1 1177356032 72592768 13469568
08:29:08 1036 8192000000 10040421184 10424569856 1082107040 11.668 100 88 169 1 1165502656 65357280 18038336
08:30:12 1282 8192000000 10052497792 10439000064 1092957312 11.771 100 92 176 2 1173495536 62639520 17898704
08:31:14 1040 8192000000 10039888512 10423607296 1077863920 11.627 100 95 184 0 1164333408 67281504 19187984
08:32:15 1065 8192000000 10050514464 10431205376 1075817120 11.608 100 96 184 2 1167374208 74808704 16748384
08:33:18 1109 8192000000 10022261792 10400612352 1057003712 11.428 100 83 159 2 1141955520 69191792 15760016
08:34:20 1905 8192000000 9984548400 10364551168 1051438672 11.374 100 74 140 2 1104033008 38592336 14002000
08:35:23 3252 8192000000 9963371296 10339106816 1020335488 11.075 99 58 108 1 1085794608 54600544 10858576
08:36:27 1201 8192000000 9987884832 10359865344 1032220736 11.190 100 89 177 1 1109991984 59182288 18588960
08:37:29 990 8192000000 9998040352 10377854976 1047812384 11.340 100 83 148 3 1117063440 54898240 14352816
08:38:37 5075 8192000000 10000013296 10382725120 1056276320 11.421 100 65 115 2 1117001408 49777392 10947696
08:39:48 24490 8192000000 10045572160 10438610944 1076555136 11.615 100 63 127 0 1160826688 71500448 12771104
08:41:17 4223 8192000000 10090749760 10486419456 1090030336 11.743 100 74 140 2 1212001872 107815536 14156000
08:42:28 4959 8192000000 10081778528 10483310592 1105946960 11.894 100 56 104 0 1201755296 85122080 10686256
08:43:37 6149 8192000000 10131353168 10536988672 1109893296 11.931 100 72 140 0 1259918944 133949456 16076192
08:44:50 10655 8192000000 10188833616 10588798976 1125874528 12.082 100 68 135 0 1300408048 159682592 14850928
08:46:11 1608 8192000000 10241731952 10637197312 1138251664 12.199 100 89 170 1 1350337216 194369728 17715824
08:47:17 1299 8192000000 10250274176 10656395264 1152582048 12.334 100 84 159 1 1360718096 191291024 16845024
08:48:20 4719 8192000000 10272789168 10679648256 1168048192 12.479 100 70 138 1 1392100528 209266096 14786240
08:49:27 1747 8192000000 10310820176 10717163520 1171819808 12.514 100 86 163 1 1422680992 233089072 17772112
08:50:32 810 8192000000 10343834112 10773426176 1177643008 12.568 100 94 182 3 1454360784 254941136 21776640
08:51:37 1471 8192000000 10337998640 10771492864 1195579936 12.735 100 93 184 1 1446593056 229690704 21322416
08:52:44 1726 8192000000 10392885072 10798395392 1197105856 12.749 100 91 179 0 1499952064 281816224 21029984
08:53:50 1421 8192000000 10419345712 10826805248 1207716992 12.848 100 92 180 0 1524253776 295859136 20677648
08:54:55 950 8192000000 10455550128 10865672192 1218928512 12.952 100 98 194 3 1563470032 322607200 21934320
08:55:58 737 8192000000 10490797488 10914246656 1220006560 12.962 100 93 180 0 1598792496 359662976 19122960
08:57:01 1628 8192000000 10511302848 10920837120 1201775248 12.793 100 87 166 0 1620279616 399492272 19012096
08:58:05 849 8192000000 10533090960 10968567808 1199358608 12.770 100 99 195 2 1638922880 415632816 23931456
08:59:08 713 8192000000 10581021216 10990428160 1213297872 12.900 100 97 185 1 1683929328 449565360 21066096
09:00:11 888 8192000000 10563659280 11028459520 1197103536 12.749 100 87 172 1 1669121920 451451200 20567184
09:01:14 13302 8192000000 10551681824 10983493632 1196744176 12.746 100 48 94 1 1657723344 448572304 12406864
09:02:33 8619 8192000000 10621714112 11052789760 1184130384 12.629 99 55 108 0 1722557600 523596240 14830976
09:04:00 7644 8192000000 10612793392 11093102592 1192988912 12.711 100 56 111 0 1739253440 531179040 15085488
09:05:10 12066 8192000000 10576638304 11080409088 1189224528 12.676 100 45 90 0 1701959488 500551952 12183008
09:06:41 11587 8192000000 10474926016 10965794816 1183350096 12.621 100 41 82 1 1573289632 379125328 10814208
09:07:54 51447 8192000000 10313767568 10840072192 1162571744 12.427 100 17 34 0 1453884832 286756624 4556464
09:09:48 46672 8192000000 10070573664 10530021376 1085638752 11.701 94 17 35 0 1125593648 36918864 3036032
09:11:36 53024 8192000000 10113078704 10546688000 1115189728 11.982 98 51 102 0 1203577632 77279280 11108624
09:14:20 133673 8192000000 10128130544 10577055744 1110141504 11.934 98 31 56 0 1240942752 124422896 6378352
09:17:34 52623 8192000000 10063521312 10513432576 1097266608 11.812 97 22 44 0 1136734752 34808880 4659264
09:19:28 52566 8192000000 10071857216 10527473664 1132659632 12.146 100 30 51 1 1175312864 37869936 4783296
09:21:21 10577 8192000000 10126563536 10558935040 1137796976 12.195 100 57 113 1 1214375392 65314336 11264080
09:22:33 33656 8192000000 10183437072 10608558080 1128322368 12.106 98 53 103 0 1261723744 121439712 11961664
09:24:09 60150 8192000000 10220297712 10681282560 1130199600 12.123 98 31 60 0 1302227456 164914080 7113776
09:26:11 80186 8192000000 10206973072 10704175104 1126988400 12.093 99 22 41 1 1311772000 179452416 5331184
09:28:34 60781 8192000000 10118006192 10631766016 1139534000 12.211 100 28 56 0 1265051664 119349248 6168416
09:30:44 44040 8192000000 10109095056 10546774016 1108696368 11.920 97 34 68 0 1156331792 40847984 6787440
09:32:37 39761 8192000000 10083400832 10540625920 1115284560 11.982 98 33 60 1 1167251152 46308064 5658528
09:35:10 18198 8192000000 10084502896 10507620352 1107554176 11.909 98 48 93 1 1158233504 41942304 8737024
09:36:35 18408 8192000000 10155347024 10587152384 1142094832 12.235 100 50 96 0 1225861984 74456288 9310864
09:37:57 33873 8192000000 10082589728 10530357248 1126756128 12.091 99 15 30 0 1169184256 39753984 2674144
09:39:34 128136 8192000000 10054853216 10525569024 1095061152 11.791 96 15 30 0 1159814416 61674480 3078784
09:42:53 21556 8192000000 10070317408 10524344320 1128417632 12.106 100 28 54 0 1168684912 34660320 5606960
09:44:18 65886 8192000000 10081182192 10521075712 1109586208 11.928 98 23 46 0 1158288352 43989104 4713040
09:46:24 50318 8192000000 10088160896 10547769344 1113364544 11.964 98 17 34 0 1182619008 65511424 3743040
09:49:06 46010 8192000000 10089761488 10536677376 1111086816 11.943 98 20 40 1 1189273600 73388144 4798640
09:50:53 40827 8192000000 10145874880 10592964608 1136974944 12.187 99 19 38 0 1228777744 87717136 4085664
09:52:34 27761 8192000000 10149580832 10591293440 1130124304 12.123 98 23 46 1 1228744112 93444944 5174864
09:54:02 53315 8192000000 10134980576 10591055872 1139025408 12.206 99 20 40 1 1224556368 80638144 4892816
09:55:56 61884 8192000000 10109937968 10581803008 1152555808 12.333 100 28 54 0 1233945136 75585984 5803344
09:58:05 59289 8192000000 10124576992 10592792576 1100085264 11.838 95 24 44 1 1185907920 80957296 4865360
10:00:08 46000 8192000000 10112559568 10567692288 1131110496 12.132 98 34 68 0 1180911344 43022176 6778672
10:02:02 74001 8192000000 10154471360 10653364224 1106946848 11.904 96 25 50 0 1219190368 107088816 5154704
10:04:16 123849 8192000000 10055997920 10539487232 1110142656 11.934 97 26 52 0 1181063600 64371808 6549136
10:07:20 59738 8192000000 10041805024 10501218304 1126669472 12.090 99 8 16 1 1146335216 17864608 1801136
10:09:22 64495 8192000000 10059379456 10526318592 1134308784 12.162 99 24 47 0 1162794464 23294304 5191376
10:11:57 86007 8192000000 10068228736 10540953600 1080203008 11.649 94 9 18 0 1123276464 41091696 1981760
10:14:24 19118 8192000000 10076714480 10520866816 1132842464 12.148 99 23 46 0 1170423024 32605712 4974848
10:15:50 36771 8192000000 10101221808 10553348096 1099854288 11.836 96 32 64 0 1155819888 49008464 6957136
10:17:38 27613 8192000000 10175209696 10612006912 1142339520 12.238 99 41 82 0 1244427872 93617136 8471216
10:19:06 41302 8192000000 10180383088 10656935936 1131334128 12.134 98 47 94 0 1290936400 149887568 9714704
10:20:58 59663 8192000000 10109504544 10549092352 1103151136 11.868 97 26 50 0 1224676752 115701696 5823920
10:23:05 35528 8192000000 10116891408 10553597952 1142932256 12.243 100 45 90 0 1216816608 64871952 9012400
10:24:41 30684 8192000000 10116501824 10554298368 1135148208 12.170 99 56 112 0 1199597472 53119520 11329744
10:26:13 64170 8192000000 10103475968 10596466688 1096707488 11.806 96 22 44 0 1193169248 91622960 4838800
10:28:22 99944 8192000000 10048842704 10482581504 1106633520 11.901 98 19 37 0 1128074592 18053584 3387488
10:31:03 53609 8192000000 10050150704 10507714560 1083935168 11.685 96 14 28 0 1134357840 47821680 2600992
10:32:59 75435 8192000000 10079890992 10535473152 1101343744 11.850 98 14 28 0 1164824272 60545600 2934928
10:35:39 101289 8192000000 10090082368 10564390912 1117754208 12.006 99 19 37 0 1188825344 67938544 3132592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-03-2023 10:39:15 to 18-03-2023 10:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-03-2023 10:39:33 to 18-03-2023 10:39:35.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          212294
MON$OLDEST_ACTIVE               215605
MON$OLDEST_SNAPSHOT             215605
MON$NEXT_TRANSACTION            215605
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-30 07:30:08.6030 GMT
MON$PAGES                       2049760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2911 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2911 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2911 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2911 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Mar 18 10:37:31 2023
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Mar 18 10:37:31 2023
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

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