Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2021 04:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16330848 kB
MemAvailable 31220336 kB
Buffers 20 kB
Cached 15212540 kB
SwapCached 4864 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.0
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-01-18 04:26:58
DTS_END                         2021-01-18 04:26:58.7860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52492
TRN_ID                          245356
ATT_ID                          673
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: 9178.83 22511 1101459
customer order: creation 180.08 396 21610
customer order: refuse 14.65 315 1758
order to supplier: creation 47.70 1365 5724
order to supplier: removal 3.28 114151 393
invoice (draft): creation 48.58 3380 5830
invoice (draft): removal 50.74 978 6089
invoice accept: apply 42.72 16110 5126
invoice accept: cancel 45.73 891 5487
customer reserve: creation 3998.03 155 479764
customer reserve: removal 4439.53 66 532744
realization accept: apply 86.93 187 10431
realization accept: cancel 87.03 120 10443
payment from customer: creation 61.91 172 7429
payment from customer: removal 5.85 89 702
payment to supplier: creation 50.98 142 6118
payment to supplier: removal 3.55 75 426
service: total inventory turnovers 4.10 3270 492
service: total monetary turnovers 6.55 499 786
service: aggregate perf. data 0.85 18983 102
service: refresh index statistics 0.04 288880 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-01-2021 04:29:24 to 18-01-2021 04:29:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2734
TEST_TIME 2 2653
TEST_TIME 3 2370
TEST_TIME 4 3394
TEST_TIME 5 3767
TEST_TIME 6 3710
TEST_TIME 7 4307
TEST_TIME 8 5013
TEST_TIME 9 5666
TEST_TIME 10 5798
TEST_TIME 11 6212
TEST_TIME 12 6343
TEST_TIME 13 6424
TEST_TIME 14 6636
TEST_TIME 15 6577
TEST_TIME 16 6808
TEST_TIME 17 7109
TEST_TIME 18 7941
TEST_TIME 19 7912
TEST_TIME 20 8013
TEST_TIME 21 8210
TEST_TIME 22 8217
TEST_TIME 23 8707
TEST_TIME 24 9151
TEST_TIME 25 9119
TEST_TIME 26 9737
TEST_TIME 27 11315
TEST_TIME 28 11188
TEST_TIME 29 11188
TEST_TIME 30 10951
TEST_TIME 31 10954
TEST_TIME 32 10926
TEST_TIME 33 10888
TEST_TIME 34 10843
TEST_TIME 35 10816
TEST_TIME 36 10787
TEST_TIME 37 10946
TEST_TIME 38 10764
TEST_TIME 39 10522
TEST_TIME 40 10466
TEST_TIME 41 10343
TEST_TIME 42 10283
TEST_TIME 43 10144
TEST_TIME 44 10139
TEST_TIME 45 10319
TEST_TIME 46 10314
TEST_TIME 47 10242
TEST_TIME 48 10149
TEST_TIME 49 10031
TEST_TIME 50 9989
TEST_TIME 51 9869
TEST_TIME 52 9752
TEST_TIME 53 9700
TEST_TIME 54 9770
TEST_TIME 55 9805
TEST_TIME 56 9765
TEST_TIME 57 9719
TEST_TIME 58 9750
TEST_TIME 59 9731
TEST_TIME 60 9821
TEST_TIME 61 9816
TEST_TIME 62 9840
TEST_TIME 63 9836
TEST_TIME 64 9908
TEST_TIME 65 9816
TEST_TIME 66 9681
TEST_TIME 67 9654
TEST_TIME 68 9600
TEST_TIME 69 9523
TEST_TIME 70 9479
TEST_TIME 71 9446
TEST_TIME 72 9421
TEST_TIME 73 9377
TEST_TIME 74 9424
TEST_TIME 75 9420
TEST_TIME 76 9371
TEST_TIME 77 9437
TEST_TIME 78 9436
TEST_TIME 79 9556
TEST_TIME 80 9592
TEST_TIME 81 9633
TEST_TIME 82 9627
TEST_TIME 83 9605
TEST_TIME 84 9500
TEST_TIME 85 9468
TEST_TIME 86 9395
TEST_TIME 87 9326
TEST_TIME 88 9309
TEST_TIME 89 9265
TEST_TIME 90 9322
TEST_TIME 91 9282
TEST_TIME 92 9290
TEST_TIME 93 9289
TEST_TIME 94 9350
TEST_TIME 95 9350
TEST_TIME 96 9327
TEST_TIME 97 9366
TEST_TIME 98 9431
TEST_TIME 99 9494
TEST_TIME 100 9470
TEST_TIME 101 9482
TEST_TIME 102 9400
TEST_TIME 103 9343
TEST_TIME 104 9304
TEST_TIME 105 9305
TEST_TIME 106 9276
TEST_TIME 107 9251
TEST_TIME 108 9250
TEST_TIME 109 9252
TEST_TIME 110 9324
TEST_TIME 111 9294
TEST_TIME 112 9321
TEST_TIME 113 9362
TEST_TIME 114 9322
TEST_TIME 115 9387
TEST_TIME 116 9386
TEST_TIME 117 9334
TEST_TIME 118 9266
TEST_TIME 119 9235
TEST_TIME 120 9184
TEST_TIME 121 9109

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-01-2021 04:29:28 to 18-01-2021 04:29:31.

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 1545313 1545313 0 0.00 0 30672 6 0 0
doc_list_biud 1545308 1545308 0 0.00 0 39055 29 0 0
sp_add_invoice_to_stock 5183 5126 57 1.09 30 154543 16110 0 57
sp_cancel_adding_invoice 5487 5487 0 0.00 5 82412 891 0 0
sp_cancel_client_order 1758 1758 0 0.00 5 21288 315 0 0
sp_cancel_customer_reserve 532744 532744 0 0.00 1 21971 66 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 4662 90 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 2774 76 0 0
sp_cancel_supplier_invoice 6094 6089 5 0.08 2 39058 979 0 5
sp_cancel_supplier_order 393 393 0 0.00 7 1076857 114151 0 0
sp_cancel_write_off 10443 10443 0 0.00 1 22187 120 0 0
sp_client_order 21610 21610 0 0.00 4 17193 396 0 0
sp_customer_reserve 479764 479764 0 0.00 0 61279 156 0 0
sp_fill_shopping_cart 33157 33157 0 0.00 1 20881 43 0 0
sp_fill_shopping_cart_clo_res 479756 479756 0 0.00 0 18495 17 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 33478 381 0 0
sp_kill_cost_storno 17058 17058 0 0.00 0 22088 57 0 0
sp_kill_qstorno_ret_qs2qd 1082413 1082413 0 0.00 0 39054 36 0 0
sp_kill_qty_storno 572461 572461 0 0.00 0 39055 78 0 0
sp_lock_selected_doc 21449 21449 0 0.00 0 7750 7 0 0
sp_make_cost_storno 29094 29094 0 0.00 0 30606 62 0 0
sp_multiply_rows_for_pdistr 29094 29094 0 0.00 0 26282 42 0 0
sp_multiply_rows_for_qdistr 21610 21610 0 0.00 0 14985 219 0 0
sp_pay_from_customer 7429 7429 0 0.00 4 30761 172 0 0
sp_pay_to_supplier 6118 6118 0 0.00 4 30758 142 0 0
sp_payment_common 13547 13547 0 0.00 1 25117 41 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 21281 281 0 0
sp_qd_handle_on_invoice_upd_sts 10603 10603 0 0.00 0 33739 361 0 0
sp_qd_handle_on_reserve_upd_sts 10431 10431 0 0.00 0 14704 45 0 0
sp_reserve_write_off 10431 10431 0 0.00 6 25875 188 0 0
sp_supplier_invoice 5830 5830 0 0.00 7 94705 3381 0 0
sp_supplier_order 5724 5724 0 0.00 6 27984 1365 0 0
srv_aggregate_perf_data 103 102 1 0.97 2968 441904 18983 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 3134 158069 43213 0 0
srv_find_qd_qs_mism 1051555 1051555 0 0.00 0 15004 5 0 0
srv_make_invnt_saldo 492 492 0 0.00 69 118097 3270 0 0
srv_make_money_saldo 786 786 0 0.00 1 22211 500 0 0
srv_recalc_idx_stat 5 5 0 0.00 173765 356603 288880 0 0
t$perf-abend:sp_add_invoice_to_stock 57 57 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5126 5126 0 0.00 0 11985 144 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 3739 80 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 2729 11 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 299 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 6973 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 5246 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 583 12 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 18483 1428 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 1446 5 0 0
t$perf-norm:sp_client_order 21610 21610 0 0.00 0 15426 15 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 6609 12 0 0
t$perf-norm:sp_pay_from_customer 7429 7429 0 0.00 0 5807 8 0 0
t$perf-norm:sp_pay_to_supplier 6118 6118 0 0.00 0 6428 11 0 0
t$perf-norm:sp_reserve_write_off 10431 10431 0 0.00 0 11938 11 0 0
t$perf-norm:sp_supplier_invoice 5830 5830 0 0.00 0 18179 18 0 0
t$perf-norm:sp_supplier_order 5724 5724 0 0.00 0 6430 12 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 251 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 1914 10 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 4921 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10346 10232 114 1.10 0 6593 20 0 114
v_all_customers 39438 39438 0 0.00 0 128 1 0 0
v_all_suppliers 31994 31994 0 0.00 0 129 1 0 0
v_all_wares 43216 43216 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2124 8 0 0
v_cancel_client_order 3516 3516 0 0.00 0 4195 7 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 2474 12 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 299 3 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 1068 8 0 0
v_cancel_supplier_invoice 832 822 10 1.20 0 3002 16 0 10
v_cancel_supplier_order 786 786 0 0.00 0 4327 9 0 0
v_cancel_write_off 2168 2168 0 0.00 0 5815 25 0 0
v_max_id_clo_ord 5722 5722 0 0.00 0 1742 2 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3767 23 0 0
v_max_id_ord_sup 5826 5826 0 0.00 0 7138 7 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 173 2 0 0
v_max_non_paid_realizn 3903 3903 0 0.00 0 8446 6 0 0
v_min_id_clo_ord 5722 5722 0 0.00 0 5328 10 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 10115 21 0 0
v_min_id_ord_sup 5826 5826 0 0.00 0 10411 10 0 0
v_min_non_paid_invoice 6118 6118 0 0.00 0 22951 24 0 0
v_min_non_paid_realizn 7429 7429 0 0.00 0 25114 40 0 0
v_our_firm 10830 10830 0 0.00 0 15 1 0 0
v_reserve_write_off 20862 20862 0 0.00 0 7337 8 0 0
x_lock_depdocs_on_canc_invoice 11165 11165 0 0.00 0 16733 103 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 25149 423 0 0
x_make_qstorno_1000_1200 5724 5724 0 0.00 2 27715 1290 0 0
x_make_qstorno_1200_2000 5830 5830 0 0.00 2 94301 3214 0 0
x_make_qstorno_2100_3300 479764 479764 0 0.00 0 61277 136 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2021 04:29:32 to 18-01-2021 04:29:36.

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
01:57:02 7 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
01:58:03 24085 8192000000 9717581312 10017579008 794206464 8.838 100 75 150 0 820592736 7576448 18809824
01:59:32 14972 8192000000 9774983712 10084433920 845167856 9.352 100 87 174 0 882437552 17003200 20266496
02:00:58 15821 8192000000 9816077680 10135371776 880719344 9.707 100 80 160 0 922420720 23439536 18261840
02:02:22 20294 8192000000 9880421248 10213593088 924593584 10.141 100 82 164 0 986026672 43277424 18155664
02:03:47 9389 8192000000 9944666560 10289655808 972088960 10.607 100 87 174 3 1053461920 63228224 18144736
02:05:01 4407 8192000000 9979132384 10335522816 1005067920 10.928 100 74 148 1 1095841792 76386096 14387776
02:06:07 1733 8192000000 10022124208 10384244736 1028485328 11.154 100 87 173 0 1139697584 94171184 17041072
02:07:10 1316 8192000000 10037193312 10401624064 1052654752 11.386 100 95 190 1 1150792672 78744048 19393872
02:08:12 37197 8192000000 10020483632 10407763968 1066497488 11.519 100 44 89 0 1159531312 84030704 9003120
02:09:50 4782 8192000000 9994276256 10378506240 1079262960 11.640 100 39 75 1 1105400896 17723488 8414448
02:10:58 9262 8192000000 9995979760 10397958144 1081180960 11.659 100 24 48 2 1104513824 19036368 4296496
02:12:07 6529 8192000000 10001728304 10403672064 1086197952 11.706 100 49 97 1 1111912960 15042240 10672768
02:13:14 35334 8192000000 10009129840 10428854272 1088981808 11.733 100 40 81 1 1129278832 31935904 8361120
02:14:50 5409 8192000000 10014012816 10409447424 1092331424 11.765 100 49 98 2 1111037904 7363200 11343280
02:15:56 7370 8192000000 10006976880 10417905664 1082604352 11.672 99 23 47 2 1108140432 20682096 4853984
02:17:10 4459 8192000000 9982866160 10377052160 1054510896 11.404 98 47 94 2 1074401360 10295216 9595248
02:18:17 15652 8192000000 9943918880 10311069696 991916096 10.800 95 78 158 3 1026469888 15080512 19473280
02:19:34 6147 8192000000 9934591712 10317922304 1003697504 10.914 98 47 93 0 1033835104 20550720 9586880
02:20:43 7885 8192000000 9920178784 10301644800 978320800 10.668 97 40 79 3 1002007472 14670992 9015680
02:21:53 4999 8192000000 9955961920 10338078720 1040847888 11.273 100 48 96 2 1067451856 15276912 11327056
02:23:00 61337 8192000000 9964067184 10365288448 1055991392 11.418 100 20 40 0 1091437296 31381776 4064128
02:25:02 9254 8192000000 9978706752 10382675968 1062791184 11.483 100 37 69 1 1096505168 26322496 7391488
02:26:13 8192 8192000000 9988932160 10392973312 1073904800 11.589 100 31 62 1 1106174080 25789184 6480096
02:27:27 43434 8192000000 10012241792 10410455040 1094025008 11.781 100 40 80 0 1118353600 15702448 8626144
02:29:12 78736 8192000000 10087913440 10510835712 1114325440 11.973 100 41 82 0 1212460736 89192832 8942464
02:31:35 28602 8192000000 10100110192 10517962752 1131035216 12.131 100 31 63 1 1209867184 71796784 7035184
02:33:10 10315 8192000000 10174366720 10579881984 1145239584 12.265 100 59 116 0 1290013872 132341408 12432880
02:34:25 3786 8192000000 10214844656 10624139264 1159466480 12.398 100 60 117 1 1322163040 149791600 12904960
02:35:31 9015 8192000000 10215528000 10655981568 1163477376 12.436 100 53 105 1 1325680416 149639712 12563328
02:36:44 6330 8192000000 10220557856 10628722688 1162062112 12.423 100 63 123 0 1320751536 145583984 13105440
02:37:53 7274 8192000000 10268962272 10672263168 1176535824 12.558 100 77 154 3 1376969744 183751184 16682736
02:39:03 5867 8192000000 10341472352 10745794560 1177586944 12.568 100 81 160 1 1439490992 243231584 18672464
02:40:16 4457 8192000000 10337142320 10744754176 1171623632 12.512 100 58 116 1 1432142000 246545360 13973008
02:41:24 3130 8192000000 10347375328 10754678784 1150431648 12.314 99 74 145 1 1449916240 282737312 16747280
02:42:30 3886 8192000000 10418728880 10826162176 1150909712 12.318 100 74 148 2 1519798832 351292240 17596880
02:43:39 4390 8192000000 10423905840 10837864448 1166615296 12.465 100 66 132 0 1519359264 337116240 15627728
02:44:50 8159 8192000000 10394294288 10854739968 1175562224 12.549 100 39 78 1 1513193632 327887264 9744144
02:46:18 4512 8192000000 10416832864 10825105408 1153369328 12.341 100 70 138 0 1517214656 347540752 16304576
02:47:28 14699 8192000000 10416096496 10833547264 1114094816 11.971 98 35 70 1 1487737696 364441840 9201040
02:48:50 16138 8192000000 10469709664 10904391680 1137476864 12.192 99 42 84 0 1566988320 418464864 11046592
02:50:12 12420 8192000000 10432401456 10929446912 1159197936 12.396 100 35 70 1 1556371280 387846176 9327168
02:51:26 8627 8192000000 10454565936 10899132416 1147621840 12.287 100 33 66 2 1549874048 393322400 8929808
02:53:59 55628 8192000000 10053562000 10505039872 1077415520 11.623 95 13 26 0 1157360352 77118864 2825968
02:55:56 51699 8192000000 10053660512 10479558656 1117287360 12.001 100 27 52 2 1139905536 16832272 5785904
02:57:53 30627 8192000000 10064686688 10503319552 1109142208 11.924 98 16 32 1 1145060768 32596144 3322416
02:59:26 99994 8192000000 10075847200 10516217856 1112777184 11.959 98 17 34 0 1144423520 28539072 3107264
03:02:12 158068 8192000000 10057844208 10505801728 1066626096 11.520 94 13 26 0 1113849712 44997904 2225712
03:06:01 35909 8192000000 10109073040 10540560384 1132623616 12.146 100 36 73 1 1193635584 52640944 8371024
03:09:02 41944 8192000000 10149973808 10578202624 1129850960 12.120 98 42 84 1 1212017152 72865712 9300480
03:10:46 9564 8192000000 10165690992 10607108096 1145330176 12.266 100 52 108 0 1261881264 104876064 11675024
03:12:01 37856 8192000000 10102336544 10550648832 1143823232 12.251 100 16 32 1 1202919008 55557248 3538528
03:13:41 34854 8192000000 10083307264 10549833728 1135025968 12.169 99 7 14 0 1179186400 42601392 1559040
03:16:01 79322 8192000000 10043959616 10514747392 1127075696 12.094 100 17 34 0 1159656704 28883936 3697072
03:18:23 38274 8192000000 10089301264 10531622912 1111445840 11.946 98 12 23 1 1159404096 45109504 2848752
03:20:02 85192 8192000000 10073581456 10559553536 1111030256 11.942 98 13 24 2 1169053792 55157536 2866000
03:22:30 45989 8192000000 10057872496 10497953792 1091063920 11.753 97 18 36 0 1126718816 32302304 3352592
03:24:18 36762 8192000000 10094166864 10559524864 1121124720 12.038 99 25 50 2 1200025072 73396544 5503808
03:25:55 62315 8192000000 10085371872 10555527168 1094357376 11.784 97 23 46 0 1159326320 60427408 4541536
03:27:58 50381 8192000000 10055573072 10490228736 1130788080 12.129 100 16 31 1 1168278432 34379840 3110512
03:29:48 42440 8192000000 10060834448 10567634944 1105488112 11.890 98 13 26 1 1193710016 85445856 2776048
03:31:36 86658 8192000000 10041017104 10470576128 1088174816 11.725 99 35 70 2 1129900336 35341792 6383728
03:34:08 65772 8192000000 10054473984 10493947904 1105717696 11.892 98 12 22 1 1141160928 33000544 2442688
03:36:16 51543 8192000000 10063953920 10533826560 1095540160 11.795 98 17 34 0 1158718512 58731344 4447008
03:38:18 38326 8192000000 10096379184 10529832960 1135111856 12.170 100 28 57 1 1196530384 55612048 5806480
03:40:04 70175 8192000000 10117643472 10558828544 1110913216 11.941 98 18 36 2 1198758128 83840928 4003984
03:42:23 46075 8192000000 10131853152 10611961856 1106529872 11.900 98 27 54 1 1250151504 137426576 6195056
03:44:20 29360 8192000000 10161653072 10609553408 1116049824 11.990 99 26 52 1 1235465520 113771088 5644608
03:46:01 17326 8192000000 10060692480 10481717248 1101117136 11.848 99 48 93 0 1139794528 29065216 9612176
03:47:23 85621 8192000000 10071974736 10536329216 1086356272 11.708 97 10 20 0 1133034096 44562288 2115536
03:49:50 82532 8192000000 10048397168 10483245056 1119833328 12.025 100 23 46 1 1160964064 36357200 4773536
03:52:25 62946 8192000000 10063363440 10518081536 1117713184 12.005 99 13 25 2 1149928752 29291088 2924480
03:54:28 71248 8192000000 10082631504 10574893056 1108517680 11.918 98 6 12 1 1178916288 69151488 1247120
03:56:45 39511 8192000000 10075929152 10523807744 1120895008 12.035 99 15 30 1 1154425824 30273072 3257744
03:58:25 37219 8192000000 10086329360 10527784960 1120531312 12.032 99 22 44 2 1159453600 34411808 4510480
04:00:13 35405 8192000000 10079252752 10526576640 1130029712 12.122 100 23 46 2 1184298592 49487360 4781520
04:01:48 47644 8192000000 10082286240 10521346048 1119780528 12.025 99 23 45 2 1166771056 42048496 4942032
04:03:37 124349 8192000000 10079315744 10651217920 1098264192 11.821 96 17 34 1 1229448640 127292992 3891456
04:06:43 24212 8192000000 10085484192 10503667712 1113795088 11.968 99 37 74 3 1148451808 28003776 6652944
04:08:14 106439 8192000000 10052987264 10520924160 1121834496 12.044 100 11 22 0 1160907680 37001312 2071872
04:11:09 49947 8192000000 10047584624 10506895360 1099307664 11.831 97 12 24 1 1128627856 27186624 2133568
04:13:02 37497 8192000000 10097146768 10531422208 1126784384 12.091 100 27 53 3 1176819232 43742832 6292016
04:14:49 66376 8192000000 10155890720 10650869760 1127477200 12.098 99 22 44 1 1215699520 83458304 4764016
04:16:58 40480 8192000000 10059435888 10486837248 1062246528 11.478 94 23 46 1 1105918304 39141904 4529872
04:18:47 75211 8192000000 10053333168 10518593536 1107574416 11.909 98 22 45 1 1176976512 64876192 4525904
04:21:04 34942 8192000000 10121468592 10575818752 1125182272 12.076 99 29 58 1 1207805408 76720864 5902272
04:22:48 29135 8192000000 10051375408 10472325120 1087491088 11.719 97 26 52 0 1141012896 47715488 5806320
04:26:41 38967 8192000000 9490736816 9869172736 611024128 6.941 55 2 4 0 640263456 28925344 313984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-01-2021 04:29:38 to 18-01-2021 04:29:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 57 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 18-01-2021 04:29:54 to 18-01-2021 04:29:56.

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          247301
MON$OLDEST_ACTIVE               247302
MON$OLDEST_SNAPSHOT             247302
MON$NEXT_TRANSACTION            247302
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                       2125184
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             822
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:226: 
:227: mscs-test-161.budzdorov.ru    Mon Jan 18 04:27:36 2021
:228:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:229: 
:230: 
:231: mscs-test-161.budzdorov.ru    Mon Jan 18 04:27:36 2021
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:233: 
:234: 
:235: mscs-test-161.budzdorov.ru    Mon Jan 18 04:27:36 2021
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:237: 
--- end of diff output ---

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

18.01.21 04:29:57. 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-01-18 04:29:59 No severe PSQL-related problems occured
18.01.21 04:30:01. End of report.