Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2021 07:48
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 16035444 kB
MemAvailable 30794968 kB
Buffers 0 kB
Cached 15706112 kB
SwapCached 2944 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.1
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 2021-09-30 07:46:50
DTS_END                         2021-09-30 07:46:50.6720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48018
TRN_ID                          240335
ATT_ID                          625
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: 9029.72 19134 1083566
customer order: creation 176.89 400 21227
customer order: refuse 14.39 331 1727
order to supplier: creation 46.53 1500 5584
order to supplier: removal 3.44 112345 413
invoice (draft): creation 46.86 3664 5623
invoice (draft): removal 49.88 1014 5986
invoice accept: apply 42.38 20301 5085
invoice accept: cancel 44.77 886 5372
customer reserve: creation 3897.21 203 467665
customer reserve: removal 4414.63 69 529755
realization accept: apply 83.54 192 10025
realization accept: cancel 79.08 126 9489
payment from customer: creation 60.25 106 7230
payment from customer: removal 5.55 82 666
payment to supplier: creation 49.63 125 5956
payment to supplier: removal 3.55 90 426
service: total inventory turnovers 3.96 3673 475
service: total monetary turnovers 6.11 594 733
service: aggregate perf. data 1.04 13797 125
service: refresh index statistics 0.03 223175 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-09-2021 07:48:32 to 30-09-2021 07:48:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3919
TEST_TIME 2 3107
TEST_TIME 3 3615
TEST_TIME 4 3855
TEST_TIME 5 4190
TEST_TIME 6 4346
TEST_TIME 7 4559
TEST_TIME 8 4667
TEST_TIME 9 4896
TEST_TIME 10 5308
TEST_TIME 11 5660
TEST_TIME 12 5701
TEST_TIME 13 6164
TEST_TIME 14 6095
TEST_TIME 15 6801
TEST_TIME 16 6875
TEST_TIME 17 7214
TEST_TIME 18 7587
TEST_TIME 19 8033
TEST_TIME 20 8596
TEST_TIME 21 8475
TEST_TIME 22 8692
TEST_TIME 23 8845
TEST_TIME 24 8956
TEST_TIME 25 9114
TEST_TIME 26 9655
TEST_TIME 27 10789
TEST_TIME 28 10626
TEST_TIME 29 10397
TEST_TIME 30 10237
TEST_TIME 31 9963
TEST_TIME 32 9988
TEST_TIME 33 9999
TEST_TIME 34 9840
TEST_TIME 35 9776
TEST_TIME 36 9733
TEST_TIME 37 9710
TEST_TIME 38 9808
TEST_TIME 39 9762
TEST_TIME 40 9710
TEST_TIME 41 9740
TEST_TIME 42 9570
TEST_TIME 43 9557
TEST_TIME 44 9698
TEST_TIME 45 9646
TEST_TIME 46 9648
TEST_TIME 47 9709
TEST_TIME 48 9672
TEST_TIME 49 9619
TEST_TIME 50 9692
TEST_TIME 51 9710
TEST_TIME 52 9725
TEST_TIME 53 9661
TEST_TIME 54 9913
TEST_TIME 55 9896
TEST_TIME 56 9917
TEST_TIME 57 9770
TEST_TIME 58 9662
TEST_TIME 59 9607
TEST_TIME 60 9633
TEST_TIME 61 9571
TEST_TIME 62 9472
TEST_TIME 63 9377
TEST_TIME 64 9366
TEST_TIME 65 9396
TEST_TIME 66 9369
TEST_TIME 67 9362
TEST_TIME 68 9387
TEST_TIME 69 9400
TEST_TIME 70 9405
TEST_TIME 71 9416
TEST_TIME 72 9427
TEST_TIME 73 9446
TEST_TIME 74 9425
TEST_TIME 75 9550
TEST_TIME 76 9488
TEST_TIME 77 9436
TEST_TIME 78 9328
TEST_TIME 79 9336
TEST_TIME 80 9340
TEST_TIME 81 9316
TEST_TIME 82 9252
TEST_TIME 83 9194
TEST_TIME 84 9124
TEST_TIME 85 9166
TEST_TIME 86 9179
TEST_TIME 87 9150
TEST_TIME 88 9182
TEST_TIME 89 9236
TEST_TIME 90 9205
TEST_TIME 91 9273
TEST_TIME 92 9226
TEST_TIME 93 9314
TEST_TIME 94 9325
TEST_TIME 95 9291
TEST_TIME 96 9315
TEST_TIME 97 9350
TEST_TIME 98 9280
TEST_TIME 99 9237
TEST_TIME 100 9205
TEST_TIME 101 9180
TEST_TIME 102 9141
TEST_TIME 103 9112
TEST_TIME 104 9094
TEST_TIME 105 9101
TEST_TIME 106 9097
TEST_TIME 107 9138
TEST_TIME 108 9115
TEST_TIME 109 9111
TEST_TIME 110 9159
TEST_TIME 111 9143
TEST_TIME 112 9198
TEST_TIME 113 9169
TEST_TIME 114 9194
TEST_TIME 115 9228
TEST_TIME 116 9216
TEST_TIME 117 9143
TEST_TIME 118 9109
TEST_TIME 119 9064
TEST_TIME 120 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-09-2021 07:48:36 to 30-09-2021 07:48:40.

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 1516221 1516221 0 0.00 0 14918 8 0 0
doc_list_biud 1516219 1516219 0 0.00 0 26275 31 0 0
sp_add_invoice_to_stock 5205 5085 120 2.30 31 238599 20302 0 120
sp_cancel_adding_invoice 5372 5372 0 0.00 5 66662 887 0 0
sp_cancel_client_order 1727 1727 0 0.00 5 10239 331 0 0
sp_cancel_customer_reserve 529755 529755 0 0.00 1 25238 70 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 4955 82 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 5909 91 0 0
sp_cancel_supplier_invoice 5991 5986 5 0.08 2 13846 1015 0 5
sp_cancel_supplier_order 413 413 0 0.00 7 943819 112345 0 0
sp_cancel_write_off 9489 9489 0 0.00 1 20743 127 0 0
sp_client_order 21227 21227 0 0.00 4 49877 400 0 0
sp_customer_reserve 467665 467665 0 0.00 0 185655 203 0 0
sp_fill_shopping_cart 32416 32416 0 0.00 1 22884 42 0 0
sp_fill_shopping_cart_clo_res 467652 467652 0 0.00 0 19179 17 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 40796 349 0 0
sp_kill_cost_storno 15953 15953 0 0.00 0 14907 56 0 0
sp_kill_qstorno_ret_qs2qd 1075398 1075398 0 0.00 0 22658 38 0 0
sp_kill_qty_storno 567836 567836 0 0.00 0 26274 81 0 0
sp_lock_selected_doc 20833 20833 0 0.00 0 10331 5 0 0
sp_make_cost_storno 28280 28280 0 0.00 0 11988 52 0 0
sp_multiply_rows_for_pdistr 28280 28280 0 0.00 0 10060 33 0 0
sp_multiply_rows_for_qdistr 21227 21227 0 0.00 0 15221 192 0 0
sp_pay_from_customer 7230 7230 0 0.00 4 14233 106 0 0
sp_pay_to_supplier 5956 5956 0 0.00 4 13008 125 0 0
sp_payment_common 13186 13186 0 0.00 1 14205 20 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 7948 294 0 0
sp_qd_handle_on_invoice_upd_sts 10441 10441 0 0.00 0 20906 345 0 0
sp_qd_handle_on_reserve_upd_sts 10025 10025 0 0.00 0 26274 68 0 0
sp_reserve_write_off 10025 10025 0 0.00 7 26313 192 0 0
sp_supplier_invoice 5623 5623 0 0.00 7 92863 3664 0 0
sp_supplier_order 5584 5584 0 0.00 6 24430 1500 0 0
srv_aggregate_perf_data 125 125 0 0.00 2703 153373 13797 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2021 215514 44683 0 0
srv_find_qd_qs_mism 1034900 1034900 0 0.00 0 9872 8 0 0
srv_make_invnt_saldo 475 475 0 0.00 56 125935 3674 0 0
srv_make_money_saldo 733 733 0 0.00 1 18737 594 0 0
srv_recalc_idx_stat 4 4 0 0.00 125442 294650 223176 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5085 5085 0 0.00 0 31541 173 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 4244 111 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 4007 14 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 3050 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 564 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 329 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 690 14 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 22769 1398 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2736 8 0 0
t$perf-norm:sp_client_order 21227 21227 0 0.00 0 17834 24 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 3766 7 0 0
t$perf-norm:sp_pay_from_customer 7230 7230 0 0.00 0 9214 17 0 0
t$perf-norm:sp_pay_to_supplier 5956 5956 0 0.00 0 11146 11 0 0
t$perf-norm:sp_reserve_write_off 10025 10025 0 0.00 0 16870 12 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 32884 28 0 0
t$perf-norm:sp_supplier_order 5584 5584 0 0.00 0 7989 17 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 1064 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 328 7 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 2712 15 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 3924 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10378 10138 240 2.31 0 6190 17 0 240
v_all_customers 38570 38570 0 0.00 0 38 1 0 0
v_all_suppliers 30788 30788 0 0.00 0 23 1 0 0
v_all_wares 42454 42454 0 0.00 0 199 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2202 10 0 0
v_cancel_client_order 3454 3454 0 0.00 0 3724 9 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 4944 10 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 763 4 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 977 5 0 0
v_cancel_supplier_invoice 808 798 10 1.23 0 2168 18 0 10
v_cancel_supplier_order 826 826 0 0.00 0 471 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 4410 14 0 0
v_max_id_clo_ord 5580 5580 0 0.00 0 275 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 3842 12 0 0
v_max_id_ord_sup 5609 5609 0 0.00 0 5469 4 0 0
v_max_non_paid_invoice 1751 1751 0 0.00 0 4232 6 0 0
v_max_non_paid_realizn 3980 3980 0 0.00 0 2086 3 0 0
v_min_id_clo_ord 5580 5580 0 0.00 0 5029 6 0 0
v_min_id_clo_res 979 979 0 0.00 0 2479 11 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 6187 7 0 0
v_min_non_paid_invoice 5956 5956 0 0.00 0 8833 13 0 0
v_min_non_paid_realizn 7230 7230 0 0.00 0 14202 13 0 0
v_our_firm 10384 10384 0 0.00 0 15 1 0 0
v_reserve_write_off 20050 20050 0 0.00 0 3721 5 0 0
x_lock_depdocs_on_canc_invoice 10959 10959 0 0.00 0 10593 102 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 4919 325 0 0
x_make_qstorno_1000_1200 5584 5584 0 0.00 2 24421 1424 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 2 89369 3501 0 0
x_make_qstorno_2100_3300 467665 467665 0 0.00 0 185651 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2021 07:48:41 to 30-09-2021 07:48:45.

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
05:16:54 5 8192000000 8799396608 8810909696 4478816 0.054 2 1 2 0 4578208 63616 35776
05:17:55 18273 8192000000 9721477216 10016141312 801282160 8.909 100 90 180 1 831253936 7661104 22310672
05:19:23 16071 8192000000 9765613424 10071052288 838421360 9.284 100 87 174 0 871560496 12640816 20498320
05:20:51 11771 8192000000 9802058000 10118586368 871280528 9.613 100 82 163 0 913375072 23975136 18119408
05:22:07 9248 8192000000 9860365184 10188972032 917056320 10.067 100 78 154 1 966771456 32877248 16837888
05:23:18 10580 8192000000 9906408288 10249920512 956816048 10.458 100 84 168 1 1020045744 45518336 17711360
05:24:34 5244 8192000000 9961741088 10317836288 990089520 10.782 100 80 160 0 1081776256 75721216 15965520
05:25:41 3779 8192000000 10011300800 10370322432 1022868784 11.100 100 79 158 1 1112726256 73968720 15888752
05:26:47 2143 8192000000 10014383040 10383601664 1050360048 11.364 100 86 171 0 1131523072 64028896 17134128
05:27:50 1727 8192000000 10057493520 10430902272 1067595968 11.529 100 87 174 1 1172973392 87953408 17424016
05:28:53 10435 8192000000 10000947680 10385063936 1076117184 11.610 100 51 103 1 1112465840 25704080 10644576
05:30:05 4629 8192000000 9999201104 10390769664 1083686416 11.683 100 49 97 2 1111598208 17786288 10125504
05:31:12 8267 8192000000 10008740800 10409259008 1087869584 11.722 100 40 81 1 1108901728 12837200 8194944
05:32:22 7424 8192000000 10018794512 10418987008 1094713136 11.787 100 34 68 0 1128131024 26319904 7097984
05:33:33 25404 8192000000 10027128320 10422669312 1099707760 11.835 100 47 93 3 1128257456 18223344 10326352
05:34:59 3884 8192000000 10046390208 10443993088 1106083408 11.895 100 64 123 4 1151432512 31609136 13739968
05:36:06 10225 8192000000 10047222720 10460012544 1103861280 11.874 100 46 92 2 1137157168 22951776 10344112
05:37:19 9841 8192000000 9974031056 10367557632 1046019344 11.322 98 24 49 0 1076100896 24617440 5464112
05:38:32 27078 8192000000 9973796048 10373763072 1030617968 11.174 97 36 71 2 1063570096 25279776 7672352
05:39:59 3334 8192000000 9967940464 10352254976 1037287584 11.239 99 60 119 1 1075447648 24394032 13766032
05:41:03 5105 8192000000 9941800176 10331688960 1030009936 11.169 100 30 59 1 1058823024 22483952 6329136
05:42:13 6517 8192000000 9951455184 10339000320 1030830160 11.176 99 51 102 1 1053697856 9966768 12900928
05:43:20 38796 8192000000 9956679536 10357035008 1041421632 11.278 99 29 58 2 1070346720 22552624 6372464
05:45:04 5727 8192000000 9988244144 10394746880 1070894416 11.561 100 49 98 1 1103053152 20353936 11804800
05:46:12 8069 8192000000 10019466704 10415042560 1089411152 11.737 100 53 106 2 1119432592 18735072 11286368
05:47:21 67517 8192000000 10023604640 10430025728 1100778560 11.845 100 39 77 1 1120534624 11101312 8654752
05:49:33 3516 8192000000 10074016400 10474823680 1121318016 12.039 100 51 102 1 1190580768 57877168 11385584
05:50:43 17196 8192000000 10120548864 10526081024 1133310448 12.153 100 62 124 0 1231322560 84427136 13584976
05:52:04 15929 8192000000 10165001584 10574340096 1153486704 12.342 100 51 101 2 1268319408 103994832 10837872
05:53:41 18668 8192000000 10223497536 10641338368 1169885632 12.496 100 50 99 1 1329741392 148809456 11046304
05:55:10 3813 8192000000 10275154336 10684088320 1176865408 12.561 100 68 135 0 1383278752 191570032 14843312
05:56:18 4233 8192000000 10276926304 10706444288 1172641408 12.522 100 61 120 0 1395740160 209246624 13852128
05:57:29 8182 8192000000 10312939536 10731446272 1178259920 12.574 100 59 118 1 1410746368 219145872 13340576
05:58:46 4457 8192000000 10340074512 10755366912 1172089024 12.516 100 74 146 0 1441083680 252228704 16765952
05:59:55 2904 8192000000 10313043440 10723840000 1145698576 12.269 99 70 138 1 1412046496 250448688 15899232
06:01:08 8230 8192000000 10327912880 10765996032 1159831856 12.402 100 63 124 0 1429120400 255131872 14156672
06:02:29 4913 8192000000 10299562288 10727514112 1148518640 12.296 100 62 121 0 1408491392 245620752 14352000
06:03:40 2416 8192000000 10347103216 10758344704 1127370224 12.097 98 81 161 0 1435421984 289475040 18576720
06:04:50 2018 8192000000 10323893088 10724843520 1139208320 12.208 100 86 170 0 1417611904 258545280 19858304
06:05:56 5508 8192000000 10329396960 10774515712 1149310368 12.303 100 58 117 0 1442974992 279381840 14282784
06:07:07 19662 8192000000 10245791744 10657034240 1108108240 11.915 98 31 61 0 1324519088 209142160 7268688
06:08:33 4531 8192000000 10301432208 10715623424 1137053120 12.188 99 53 105 2 1392673776 242309856 13310800
06:09:41 7991 8192000000 10324778480 10755796992 1153628624 12.344 100 39 78 0 1430968272 267776944 9562704
06:11:13 23073 8192000000 10358525152 10816659456 1123273904 12.058 99 25 50 2 1440290384 309940048 7076432
06:12:57 44971 8192000000 10081297168 10586120192 1106082160 11.895 99 11 22 0 1278619824 169654880 2882784
06:14:43 65223 8192000000 10066890384 10486964224 1091430208 11.756 98 46 92 2 1130828864 29081696 10316960
06:16:49 67030 8192000000 10072236464 10513797120 1107792880 11.912 99 29 57 1 1177826160 64279712 5753568
06:18:58 215514 8192000000 10121992400 10576809984 1067869600 11.532 94 22 44 3 1101372800 28786496 4716704
06:23:36 23321 8192000000 10189260352 10630197248 1152257456 12.331 100 42 84 2 1296816400 134155680 10403264
06:25:09 19314 8192000000 10138457456 10633670656 1138177376 12.198 99 16 32 0 1237700160 95846896 3675888
06:26:31 32906 8192000000 10141131696 10576281600 1146285120 12.275 100 24 48 1 1232017104 79287920 6444064
06:28:05 47688 8192000000 10152930464 10612887552 1156700000 12.372 100 11 22 0 1253115376 93940896 2474480
06:31:08 18716 8192000000 10104853472 10547122176 1146862688 12.280 100 42 84 0 1186721728 31474352 8384688
06:32:42 49520 8192000000 10097283248 10579795968 1140907456 12.224 99 24 48 0 1213395744 67610864 4877424
06:34:40 45111 8192000000 10097392480 10545811456 1084070000 11.686 94 16 32 0 1142545216 55123712 3351504
06:36:36 41138 8192000000 10100136144 10550845440 1138473744 12.201 100 21 42 0 1199537648 56822240 4241664
06:38:27 66001 8192000000 10159577776 10635825152 1115093456 11.981 97 18 37 0 1213480176 94199584 4187136
06:40:35 51652 8192000000 10101894176 10545885184 1132146384 12.142 99 32 62 2 1202555696 64432144 5977168
06:42:30 120268 8192000000 10066576192 10508976128 1094178464 11.782 96 33 66 0 1134466896 33348576 6939856
06:45:45 52612 8192000000 10087898992 10579386368 1141207536 12.227 100 26 52 1 1204787632 57630288 5949808
06:47:43 61331 8192000000 10056857712 10501431296 1090165408 11.744 96 13 26 1 1136880592 44330464 2384720
06:50:59 78768 8192000000 10036706592 10490961920 1049146080 11.352 93 15 30 1 1121340272 68872960 3321232
06:53:25 43682 8192000000 10076623136 10528858112 1078606304 11.634 95 28 56 0 1136365200 51678848 6080048
06:55:09 66430 8192000000 10082742864 10532036608 1089128416 11.734 97 15 30 0 1146872432 54610528 3133488
06:57:24 72320 8192000000 10072948592 10493341696 1063034240 11.486 95 33 64 1 1121428656 51335632 7058784
06:59:40 32046 8192000000 10084982064 10506379264 1110530160 11.937 100 43 86 1 1168232160 49172544 8529456
07:01:12 45530 8192000000 10076098704 10584162304 1114930544 11.979 100 38 76 0 1251944960 128796368 8218048
07:02:58 27175 8192000000 10126677104 10552094720 1123577920 12.061 100 51 102 0 1188169712 53978112 10613680
07:04:32 65395 8192000000 10066736816 10505052160 1100125168 11.839 98 31 62 1 1175029520 68531264 6373088
07:06:46 37473 8192000000 10056743168 10506866688 1095392896 11.794 98 29 58 0 1164836400 62712912 6730592
07:08:25 63849 8192000000 10057514560 10499563520 1096988624 11.809 98 11 20 1 1134823584 35307504 2527456
07:10:34 92730 8192000000 10040249408 10533945344 1080891424 11.656 96 12 24 2 1146901072 62927520 3082128
07:13:37 37068 8192000000 10140124928 10597339136 1126380448 12.087 99 43 86 0 1226132192 90351776 9399968
07:15:18 67758 8192000000 10091752960 10520301568 1106917792 11.903 97 14 28 0 1155035440 45281280 2836368
07:17:30 41037 8192000000 10102596176 10590076928 1139000832 12.206 100 20 40 0 1218125248 75168864 3955552
07:19:12 75497 8192000000 10076895664 10527272960 1126794912 12.091 99 18 36 0 1194531520 63958832 3777776
07:21:37 47699 8192000000 10126184096 10560524288 1137289360 12.190 100 44 88 0 1222230512 76274032 8667120
07:23:25 104577 8192000000 10077057744 10509340672 1121467840 12.041 98 17 33 1 1176436496 51808576 3160080
07:26:18 17336 8192000000 10066393872 10505961472 1126843456 12.092 99 38 76 0 1170692032 35341152 8507424
07:27:35 69162 8192000000 10079479568 10545430528 1128101776 12.103 99 14 28 0 1180589424 49290800 3196848
07:29:45 53667 8192000000 10091556512 10575880192 1108228112 11.916 97 9 18 0 1161843024 52211904 1403008
07:31:42 49040 8192000000 10087445968 10524307456 1106528160 11.900 97 23 46 0 1143327632 31673648 5125824
07:33:32 62503 8192000000 10091122512 10528866304 1106850416 11.903 97 26 52 0 1142106912 30339808 4916688
07:35:47 82405 8192000000 10096426448 10565668864 1126146752 12.085 98 18 36 0 1170819744 40779808 3893184
07:38:14 63559 8192000000 10052622928 10521796608 1061661904 11.472 93 11 22 1 1113026720 48931344 2433472
07:40:18 34178 8192000000 10119865984 10595389440 1107315808 11.907 98 33 66 0 1183345520 68683152 7346560
07:42:01 93253 8192000000 10051382384 10546397184 1101993472 11.857 98 11 22 0 1157284320 53302400 1988448
07:44:35 69525 8192000000 10047116144 10509508608 1095593392 11.796 97 15 30 1 1145221024 46431904 3195728
07:46:45 18860 8192000000 9443304432 9805533184 566937888 6.472 51 2 4 0 579497760 12488752 71120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-09-2021 07:48:47 to 30-09-2021 07:49:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2021 07:49:03 to 30-09-2021 07:49:05.

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          240644
MON$OLDEST_ACTIVE               240645
MON$OLDEST_SNAPSHOT             240645
MON$NEXT_TRANSACTION            240645
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                       2038240
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2619 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2619 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2619 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2619 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 ---
--- end of diff output ---

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

30.09.21 07:49:06. 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
2021-09-30 07:49:08 No severe PSQL-related problems occured
30.09.21 07:49:10. End of report.