Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 13:37
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 16198904 kB
MemAvailable 30854916 kB
Buffers 0 kB
Cached 15468736 kB
SwapCached 340 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-07 13:37:05
DTS_END                         2021-09-07 13:37:05.6970
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/59552
TRN_ID                          223624
ATT_ID                          595
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: 8746.38 28683 1049566
customer order: creation 159.07 383 19089
customer order: refuse 13.21 410 1585
order to supplier: creation 43.74 1504 5249
order to supplier: removal 3.59 118613 431
invoice (draft): creation 43.19 4032 5183
invoice (draft): removal 49.16 1039 5899
invoice accept: apply 39.98 21984 4798
invoice accept: cancel 44.06 1056 5287
customer reserve: creation 3706.42 215 444770
customer reserve: removal 4365.55 77 523866
realization accept: apply 78.09 213 9371
realization accept: cancel 78.18 132 9382
payment from customer: creation 56.83 130 6820
payment from customer: removal 5.72 98 686
payment to supplier: creation 45.93 149 5511
payment to supplier: removal 3.43 123 411
service: total inventory turnovers 3.61 4368 433
service: total monetary turnovers 5.73 672 687
service: aggregate perf. data 0.87 16792 104
service: refresh index statistics 0.03 401663 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-09-2021 13:38:19 to 07-09-2021 13:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3334
TEST_TIME 2 6327
TEST_TIME 3 6331
TEST_TIME 4 6611
TEST_TIME 5 6078
TEST_TIME 6 5947
TEST_TIME 7 5824
TEST_TIME 8 6410
TEST_TIME 9 6501
TEST_TIME 10 6630
TEST_TIME 11 6661
TEST_TIME 12 6623
TEST_TIME 13 6639
TEST_TIME 14 6595
TEST_TIME 15 6634
TEST_TIME 16 6864
TEST_TIME 17 7169
TEST_TIME 18 7178
TEST_TIME 19 7388
TEST_TIME 20 7262
TEST_TIME 21 7510
TEST_TIME 22 7675
TEST_TIME 23 7925
TEST_TIME 24 7889
TEST_TIME 25 8158
TEST_TIME 26 8101
TEST_TIME 27 8262
TEST_TIME 28 8519
TEST_TIME 29 8879
TEST_TIME 30 9184
TEST_TIME 31 10174
TEST_TIME 32 10484
TEST_TIME 33 10316
TEST_TIME 34 10068
TEST_TIME 35 10140
TEST_TIME 36 10102
TEST_TIME 37 10067
TEST_TIME 38 9973
TEST_TIME 39 9893
TEST_TIME 40 9869
TEST_TIME 41 9706
TEST_TIME 42 9574
TEST_TIME 43 9553
TEST_TIME 44 9493
TEST_TIME 45 9450
TEST_TIME 46 9342
TEST_TIME 47 9260
TEST_TIME 48 9274
TEST_TIME 49 9278
TEST_TIME 50 9207
TEST_TIME 51 9197
TEST_TIME 52 9156
TEST_TIME 53 9193
TEST_TIME 54 9171
TEST_TIME 55 9163
TEST_TIME 56 9220
TEST_TIME 57 9252
TEST_TIME 58 9219
TEST_TIME 59 9271
TEST_TIME 60 9301
TEST_TIME 61 9311
TEST_TIME 62 9341
TEST_TIME 63 9343
TEST_TIME 64 9405
TEST_TIME 65 9290
TEST_TIME 66 9184
TEST_TIME 67 9136
TEST_TIME 68 9081
TEST_TIME 69 9081
TEST_TIME 70 9001
TEST_TIME 71 9082
TEST_TIME 72 9046
TEST_TIME 73 8965
TEST_TIME 74 8978
TEST_TIME 75 8986
TEST_TIME 76 8986
TEST_TIME 77 9004
TEST_TIME 78 8990
TEST_TIME 79 8977
TEST_TIME 80 9017
TEST_TIME 81 9101
TEST_TIME 82 9104
TEST_TIME 83 9061
TEST_TIME 84 9143
TEST_TIME 85 9140
TEST_TIME 86 9146
TEST_TIME 87 9135
TEST_TIME 88 9039
TEST_TIME 89 9072
TEST_TIME 90 9001
TEST_TIME 91 9038
TEST_TIME 92 8999
TEST_TIME 93 8967
TEST_TIME 94 8917
TEST_TIME 95 8912
TEST_TIME 96 8920
TEST_TIME 97 8924
TEST_TIME 98 8932
TEST_TIME 99 8922
TEST_TIME 100 8903
TEST_TIME 101 8884
TEST_TIME 102 8992
TEST_TIME 103 8982
TEST_TIME 104 8968
TEST_TIME 105 9041
TEST_TIME 106 9021
TEST_TIME 107 9004
TEST_TIME 108 9065
TEST_TIME 109 9034
TEST_TIME 110 9024
TEST_TIME 111 8992
TEST_TIME 112 8978
TEST_TIME 113 8959
TEST_TIME 114 8917
TEST_TIME 115 8879
TEST_TIME 116 8844
TEST_TIME 117 8804
TEST_TIME 118 8806
TEST_TIME 119 8817
TEST_TIME 120 8748

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-09-2021 13:38:22 to 07-09-2021 13:38:26.

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 1460228 1460228 0 0.00 0 20806 8 0 0
doc_list_biud 1460228 1460228 0 0.00 0 80594 35 0 0
sp_add_invoice_to_stock 4974 4798 176 3.53 54 186190 21984 0 176
sp_cancel_adding_invoice 5287 5287 0 0.00 6 71844 1057 0 0
sp_cancel_client_order 1585 1585 0 0.00 4 10730 410 0 0
sp_cancel_customer_reserve 523866 523866 0 0.00 1 29709 78 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 7117 99 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 9290 124 0 0
sp_cancel_supplier_invoice 5917 5899 18 0.30 3 80600 1040 0 18
sp_cancel_supplier_order 431 431 0 0.00 9 1273111 118614 0 0
sp_cancel_write_off 9382 9382 0 0.00 1 7055 132 0 0
sp_client_order 19089 19089 0 0.00 4 50969 384 0 0
sp_customer_reserve 444770 444770 0 0.00 0 69475 215 0 0
sp_fill_shopping_cart 29501 29501 0 0.00 1 39239 57 0 0
sp_fill_shopping_cart_clo_res 444766 444766 0 0.00 0 16786 18 0 0
sp_get_clo_for_invoice 4791 4791 0 0.00 0 52783 415 0 0
sp_kill_cost_storno 15766 15766 0 0.00 0 19622 72 0 0
sp_kill_qstorno_ret_qs2qd 1063444 1063444 0 0.00 0 80592 42 0 0
sp_kill_qty_storno 560612 560612 0 0.00 0 80594 90 0 0
sp_lock_selected_doc 19624 19624 0 0.00 0 11893 5 0 0
sp_make_cost_storno 26493 26493 0 0.00 0 12744 60 0 0
sp_multiply_rows_for_pdistr 26493 26493 0 0.00 0 8433 37 0 0
sp_multiply_rows_for_qdistr 19089 19089 0 0.00 0 16143 161 0 0
sp_pay_from_customer 6820 6820 0 0.00 4 8989 130 0 0
sp_pay_to_supplier 5511 5511 0 0.00 5 11134 149 0 0
sp_payment_common 12331 12331 0 0.00 1 8214 25 0 0
sp_qd_handle_on_cancel_clo 1585 1585 0 0.00 0 10724 371 0 0
sp_qd_handle_on_invoice_upd_sts 10078 10078 0 0.00 0 38366 434 0 0
sp_qd_handle_on_reserve_upd_sts 9371 9371 0 0.00 0 26441 75 0 0
sp_reserve_write_off 9371 9371 0 0.00 6 26495 214 0 0
sp_supplier_invoice 5183 5183 0 0.00 8 90821 4033 0 0
sp_supplier_order 5249 5249 0 0.00 5 22989 1504 0 0
srv_aggregate_perf_data 104 104 0 0.00 2403 163557 16792 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2033 177934 45746 0 0
srv_find_qd_qs_mism 1003717 1003717 0 0.00 0 20804 7 0 0
srv_make_invnt_saldo 433 433 0 0.00 38 94786 4369 0 0
srv_make_money_saldo 687 687 0 0.00 1 18958 672 0 0
srv_recalc_idx_stat 4 4 0 0.00 239531 473529 401663 0 0
t$perf-abend:sp_add_invoice_to_stock 176 176 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4798 4798 0 0.00 0 18022 218 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 17896 147 0 0
t$perf-norm:sp_cancel_client_order 1585 1585 0 0.00 0 4761 17 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 6779 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 1159 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1801 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 548 15 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 21266 1600 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 1554 9 0 0
t$perf-norm:sp_client_order 19089 19089 0 0.00 0 23591 23 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 7291 14 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 9229 10 0 0
t$perf-norm:sp_pay_to_supplier 5511 5511 0 0.00 0 7895 13 0 0
t$perf-norm:sp_reserve_write_off 9371 9371 0 0.00 0 8451 11 0 0
t$perf-norm:sp_supplier_invoice 5183 5183 0 0.00 0 19272 25 0 0
t$perf-norm:sp_supplier_order 5249 5249 0 0.00 0 4552 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 476 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 87 3 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 427 9 0 0
t$perf-norm:srv_make_money_saldo 687 687 0 0.00 0 712 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 31 11 0 0
v_add_invoice_to_stock 9932 9580 352 3.54 0 3831 17 0 352
v_all_customers 35250 35250 0 0.00 0 21 1 0 0
v_all_suppliers 28838 28838 0 0.00 0 29 1 0 0
v_all_wares 38176 38176 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 10924 21 0 0
v_cancel_client_order 3170 3170 0 0.00 0 3278 7 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 4252 10 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 4228 7 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 7896 15 0 0
v_cancel_supplier_invoice 814 778 36 4.42 0 4357 27 0 36
v_cancel_supplier_order 862 862 0 0.00 0 1450 9 0 0
v_cancel_write_off 1874 1874 0 0.00 0 3354 16 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 4395 2 0 0
v_max_id_clo_res 962 962 0 0.00 0 2525 14 0 0
v_max_id_ord_sup 5165 5165 0 0.00 0 12597 6 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 163 2 0 0
v_max_non_paid_realizn 3580 3580 0 0.00 0 8209 5 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 4044 6 0 0
v_min_id_clo_res 962 962 0 0.00 0 2576 14 0 0
v_min_id_ord_sup 5165 5165 0 0.00 0 9513 19 0 0
v_min_non_paid_invoice 5511 5511 0 0.00 0 7174 18 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 5816 17 0 0
v_our_firm 9406 9406 0 0.00 0 18 1 0 0
v_reserve_write_off 18742 18742 0 0.00 0 10721 7 0 0
x_lock_depdocs_on_canc_invoice 10797 10797 0 0.00 0 20440 135 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 6499 342 0 0
x_make_qstorno_1000_1200 5249 5249 0 0.00 2 22976 1411 0 0
x_make_qstorno_1200_2000 5183 5183 0 0.00 3 90545 3804 0 0
x_make_qstorno_2100_3300 444770 444770 0 0.00 0 69464 195 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 13:38:26 to 07-09-2021 13:38:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 14715 8192000000 9719974800 10019594240 805387888 8.951 100 87 173 1 834112832 7324288 21400656
11:09:33 34760 8192000000 9770791200 10080428032 842746736 9.327 100 85 169 1 874534016 11474336 20312944
11:11:20 18231 8192000000 9815394016 10132447232 872680272 9.627 100 82 164 0 918938512 27314256 18943984
11:12:44 16969 8192000000 9868924304 10202206208 919662864 10.093 100 76 152 0 970948672 35338016 15947792
11:14:06 8231 8192000000 9932540816 10275594240 967255936 10.560 100 80 160 0 1042503200 58437008 16810256
11:15:21 3667 8192000000 9957553424 10316980224 996641936 10.846 100 75 152 1 1076230512 63893792 15694784
11:16:29 3485 8192000000 9987946240 10350718976 1020590272 11.078 100 82 163 0 1104383376 67994480 15798624
11:17:34 3876 8192000000 10021416640 10389975040 1037309600 11.239 100 80 160 0 1138379776 84876848 16193328
11:18:41 2949 8192000000 10038812928 10411724800 1054714160 11.406 100 82 160 0 1144049952 73203984 16131808
11:19:47 1491 8192000000 10050041984 10422263808 1068739024 11.540 100 87 174 0 1161448864 74882208 17827632
11:20:51 6656 8192000000 10047832912 10426286080 1075518608 11.605 100 76 153 2 1164124464 73374800 15231056
11:21:59 6825 8192000000 9997371760 10388103168 1087889472 11.723 100 39 78 0 1110798736 14434784 8474480
11:23:07 47576 8192000000 10007546944 10432765952 1096905312 11.808 100 47 96 1 1130613984 23062688 10645984
11:24:59 7923 8192000000 10027228000 10428641280 1100092352 11.839 100 38 76 0 1138444864 29663040 8689472
11:26:12 6092 8192000000 10029334320 10436673536 1102293760 11.859 100 31 62 1 1128984672 19841808 6849104
11:27:23 7106 8192000000 10017535088 10422353920 1103483568 11.871 100 30 62 1 1134249344 23794800 6970976
11:28:31 14012 8192000000 10012162704 10403057664 1059931872 11.456 96 62 124 0 1085026320 11765744 13328704
11:29:49 7633 8192000000 9989951056 10383073280 1030034320 11.169 96 44 88 2 1063998608 25084544 8879744
11:30:57 7214 8192000000 9979945264 10382426112 1034758560 11.214 97 31 61 2 1059259968 16707072 7794336
11:32:08 6747 8192000000 9936704112 10321702912 1001771440 10.896 98 33 66 2 1024696304 15392736 7532128
11:33:15 25755 8192000000 9933182128 10319302656 989545744 10.777 96 24 47 0 1020573248 26550736 4476768
11:34:43 6428 8192000000 9964875984 10356105216 1045177472 11.314 100 60 119 0 1077649072 19211728 13259872
11:35:53 12543 8192000000 9971620928 10361532416 1055369360 11.412 100 31 62 1 1082397952 19951104 7077488
11:37:06 62274 8192000000 9990039920 10384777216 1069150416 11.544 100 36 71 2 1090378192 14091792 7135984
11:39:12 3934 8192000000 10048934816 10446852096 1082365952 11.670 100 60 121 0 1158297328 62335280 13596096
11:40:22 17054 8192000000 10049691376 10452606976 1097645072 11.815 100 57 114 1 1157406416 48632704 11128640
11:41:45 5339 8192000000 10096014320 10491228160 1111867472 11.950 100 71 138 1 1187847344 61893648 14086224
11:42:53 24210 8192000000 10101896816 10519797760 1117280416 12.001 100 36 72 2 1222505904 97561696 7663792
11:44:21 6443 8192000000 10126764800 10529185792 1125640368 12.080 100 55 110 0 1226917120 89311248 11965504
11:45:35 31902 8192000000 10161712192 10587099136 1136045280 12.178 100 37 73 0 1234294096 90639136 7609680
11:47:12 32580 8192000000 10152833008 10577481728 1154715312 12.354 100 38 76 1 1253290384 90205456 8369616
11:48:48 5788 8192000000 10192998480 10603831296 1184901520 12.636 100 63 124 0 1297312608 99141280 13269808
11:50:06 2031 8192000000 10249605920 10656575488 1190593552 12.689 100 79 155 2 1349162272 141033632 17535088
11:51:14 2725 8192000000 10273681776 10677305344 1188245984 12.667 100 89 177 0 1373424032 165815040 19363008
11:52:23 4531 8192000000 10305621456 10719920128 1198146816 12.759 100 67 134 0 1415296944 202201008 14949120
11:53:31 2519 8192000000 10348021056 10766442496 1195124288 12.731 100 88 174 0 1450796176 235956736 19715152
11:54:39 3029 8192000000 10354999584 10762633216 1192819904 12.710 100 81 159 0 1455048128 244539728 17688496
11:55:45 9769 8192000000 10317208672 10731642880 1160973568 12.412 99 59 118 1 1411368960 236565120 13830272
11:57:05 4541 8192000000 10338026016 10754256896 1172569344 12.521 100 60 114 2 1435814800 250006368 13239088
11:58:14 3984 8192000000 10390569984 10799923200 1157784784 12.383 100 73 145 0 1481982896 306507872 17690240
11:59:24 7581 8192000000 10354578016 10772144128 1145113344 12.264 99 45 89 0 1454839888 298421488 11305056
12:00:39 3327 8192000000 10384788272 10790715392 1148633840 12.297 99 73 146 0 1478387472 311448912 18304720
12:01:47 2248 8192000000 10407699040 10808889344 1156728240 12.373 100 90 180 0 1500995520 323101504 21165776
12:02:53 8167 8192000000 10431725696 10861658112 1178664080 12.578 100 42 84 0 1531677264 342100224 10912960
12:04:05 5227 8192000000 10486173088 10912370688 1175784272 12.551 100 51 102 0 1584285888 395389616 13112000
12:05:18 7000 8192000000 10504050304 10952855552 1167606912 12.474 100 45 90 1 1619004848 439554928 11843008
12:06:32 12244 8192000000 10439189088 10905219072 1140014112 12.216 99 32 64 0 1527239760 378368704 8856944
12:07:59 14109 8192000000 10364863520 10839932928 1144373168 12.257 100 27 54 2 1477488576 326000256 7115152
12:09:51 30233 8192000000 10081553680 10506039296 1110740048 11.939 98 53 107 2 1161169872 38960272 11469552
12:11:24 11727 8192000000 10123655888 10548838400 1136660816 12.184 100 68 134 0 1220364144 69384848 14318480
12:12:41 78412 8192000000 10120074976 10617786368 1125556720 12.079 99 7 14 0 1223192688 96044528 1591440
12:15:05 70608 8192000000 10056466336 10514264064 1124703072 12.071 99 9 18 1 1161507056 35165408 1638576
12:17:17 177934 8192000000 10083531888 10560659456 1120469168 12.031 97 30 59 0 1177518176 50553024 6495984
12:21:21 96855 8192000000 10089504816 10569416704 1121422192 12.040 99 22 44 0 1197518832 71695248 4401392
12:24:01 37612 8192000000 10137620880 10587402240 1130894656 12.130 98 31 62 1 1217074656 78968080 7211920
12:25:49 104286 8192000000 10097411408 10602676224 1109850128 11.931 97 13 26 0 1183400016 70999664 2550224
12:28:34 120383 8192000000 10054327392 10528407552 1105696192 11.892 97 9 18 0 1165787712 58088240 2003280
12:31:35 65675 8192000000 10096721744 10546122752 1140963088 12.225 100 18 36 1 1177892656 33063552 3866016
12:33:42 71707 8192000000 10097803504 10586898432 1144615344 12.259 100 19 38 2 1214038928 65012240 4411344
12:35:55 113687 8192000000 10115834416 10632548352 1132190960 12.142 98 17 34 2 1190206384 54808784 3206640
12:38:50 63834 8192000000 10086144864 10568060928 1136595952 12.183 99 20 40 2 1197272864 56332880 4344032
12:40:59 65799 8192000000 10084813120 10592395264 1126675200 12.090 98 13 26 1 1211406080 82027360 2703520
12:43:15 73534 8192000000 10056119120 10515984384 1113320704 11.964 98 25 50 1 1144599648 26001456 5277488
12:45:33 28015 8192000000 10103336944 10536906752 1133002480 12.150 100 50 101 1 1191957760 48237872 10717408
12:47:06 46228 8192000000 10098711568 10541826048 1115095456 11.981 98 10 20 0 1205237088 88017488 2124144
12:48:57 73252 8192000000 10072722336 10531213312 1126171952 12.085 99 20 40 0 1178844624 48247968 4424704
12:51:11 73825 8192000000 10082303344 10541821952 1120812144 12.035 98 16 32 1 1168561072 44642960 3105968
12:53:25 53278 8192000000 10068881040 10526121984 1084405712 11.689 95 22 43 1 1140220048 51238304 4576032
12:55:31 28857 8192000000 10077229552 10504802304 1105733120 11.892 98 34 68 1 1167480752 53976560 7771072
12:57:02 77777 8192000000 10073215920 10580451328 1102679296 11.863 97 18 36 1 1201467936 95029328 3759312
12:59:20 22992 8192000000 10067168944 10513649664 1104893200 11.884 98 43 86 0 1164859216 51660240 8305776
13:00:44 57031 8192000000 10049545088 10542088192 1106355280 11.898 99 27 54 1 1193080048 81229904 5494864
13:02:43 84254 8192000000 10064454608 10541244416 1078773072 11.636 96 23 46 0 1121299552 38162848 4363632
13:05:08 137744 8192000000 10046349488 10505936896 1124299360 12.068 100 20 40 1 1152255760 23610864 4345536
13:08:28 83331 8192000000 10100289088 10625126400 1131306432 12.134 99 8 16 0 1217756320 84654064 1795824
13:11:00 55241 8192000000 10101403760 10552512512 1129907824 12.120 100 13 26 2 1195728176 63113904 2706448
13:13:04 84001 8192000000 10073167008 10566787072 1142283568 12.237 100 10 20 0 1198181936 54371504 1526864
13:15:28 47146 8192000000 10065717840 10507743232 1103652560 11.872 97 19 38 0 1145059360 37308416 4098384
13:17:19 12793 8192000000 10110950304 10547367936 1142859840 12.242 100 32 63 0 1201895984 53106880 5929264
13:18:35 65352 8192000000 10087141904 10593214464 1130937408 12.130 99 20 38 2 1234718160 100228736 3552016
13:21:03 28692 8192000000 10126658400 10551414784 1118878432 12.016 98 45 90 0 1193188736 65181888 9128416
13:22:41 88215 8192000000 10091029168 10516381696 1116896128 11.998 97 23 46 0 1178276960 57040656 4340176
13:25:10 31220 8192000000 10100188304 10577076224 1121836032 12.044 98 36 72 1 1230459376 101269648 7353696
13:26:54 118706 8192000000 10112273696 10583236608 1144825408 12.261 100 27 54 0 1227660160 77287888 5546864
13:30:13 52725 8192000000 10046027952 10512695296 1132865360 12.148 100 13 26 0 1172915408 37433632 2616416
13:32:13 66320 8192000000 10072400576 10553901056 1141769984 12.232 100 8 16 0 1178220240 34604864 1845392
13:34:28 61044 8192000000 10072853120 10574831616 1129889936 12.120 98 6 12 0 1179533344 48244800 1398608
13:36:31 30577 8192000000 10064567312 10535682048 1135647408 12.175 99 18 35 0 1173097536 34516800 2933328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-09-2021 13:38:32 to 07-09-2021 13:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 176 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-09-2021 13:38:48 to 07-09-2021 13:38:50.

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          223944
MON$OLDEST_ACTIVE               223945
MON$OLDEST_SNAPSHOT             223945
MON$NEXT_TRANSACTION            223945
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                       2028000
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

07.09.21 13:38:51. 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-07 13:38:53 No severe PSQL-related problems occured
07.09.21 13:38:55. End of report.