Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2021 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15707136 kB
MemAvailable 30580156 kB
Buffers 0 kB
Cached 15956140 kB
SwapCached 3704 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-24 01:37:54
DTS_END                         2021-10-24 01:37:54.6080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(601:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45746
TRN_ID                          244466
ATT_ID                          706
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: 8918.44 23516 1070213
customer order: creation 176.74 348 21209
customer order: refuse 15.28 361 1833
order to supplier: creation 47.21 1370 5665
order to supplier: removal 3.37 107490 404
invoice (draft): creation 46.10 3862 5532
invoice (draft): removal 49.83 854 5980
invoice accept: apply 41.36 20064 4963
invoice accept: cancel 45.21 991 5425
customer reserve: creation 3763.92 201 451670
customer reserve: removal 4428.98 64 531477
realization accept: apply 86.02 191 10322
realization accept: cancel 81.31 107 9757
payment from customer: creation 60.48 125 7257
payment from customer: removal 5.73 87 687
payment to supplier: creation 50.07 150 6008
payment to supplier: removal 3.48 91 418
service: total inventory turnovers 4.77 1324 572
service: total monetary turnovers 7.73 36 927
service: aggregate perf. data 0.87 20100 104
service: refresh index statistics 0.03 312508 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2021 01:38:57 to 24-10-2021 01:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3431
TEST_TIME 2 5550
TEST_TIME 3 4282
TEST_TIME 4 4253
TEST_TIME 5 4895
TEST_TIME 6 5047
TEST_TIME 7 5056
TEST_TIME 8 5252
TEST_TIME 9 5042
TEST_TIME 10 5640
TEST_TIME 11 5723
TEST_TIME 12 6670
TEST_TIME 13 7115
TEST_TIME 14 7046
TEST_TIME 15 7159
TEST_TIME 16 7375
TEST_TIME 17 7485
TEST_TIME 18 7476
TEST_TIME 19 7634
TEST_TIME 20 7819
TEST_TIME 21 7911
TEST_TIME 22 8186
TEST_TIME 23 8317
TEST_TIME 24 8548
TEST_TIME 25 8417
TEST_TIME 26 8551
TEST_TIME 27 9081
TEST_TIME 28 9185
TEST_TIME 29 9346
TEST_TIME 30 9513
TEST_TIME 31 9417
TEST_TIME 32 10262
TEST_TIME 33 10504
TEST_TIME 34 10303
TEST_TIME 35 10027
TEST_TIME 36 10180
TEST_TIME 37 10066
TEST_TIME 38 9887
TEST_TIME 39 9940
TEST_TIME 40 9816
TEST_TIME 41 9663
TEST_TIME 42 9491
TEST_TIME 43 9465
TEST_TIME 44 9409
TEST_TIME 45 9415
TEST_TIME 46 9300
TEST_TIME 47 9226
TEST_TIME 48 9170
TEST_TIME 49 9183
TEST_TIME 50 9067
TEST_TIME 51 8994
TEST_TIME 52 8928
TEST_TIME 53 8888
TEST_TIME 54 8898
TEST_TIME 55 8973
TEST_TIME 56 8934
TEST_TIME 57 8868
TEST_TIME 58 8852
TEST_TIME 59 8726
TEST_TIME 60 8653
TEST_TIME 61 8764
TEST_TIME 62 8710
TEST_TIME 63 8680
TEST_TIME 64 8792
TEST_TIME 65 8936
TEST_TIME 66 9007
TEST_TIME 67 8980
TEST_TIME 68 8972
TEST_TIME 69 8965
TEST_TIME 70 8918
TEST_TIME 71 9138
TEST_TIME 72 9164
TEST_TIME 73 9168
TEST_TIME 74 9165
TEST_TIME 75 9178
TEST_TIME 76 9100
TEST_TIME 77 9075
TEST_TIME 78 9036
TEST_TIME 79 8998
TEST_TIME 80 8986
TEST_TIME 81 8964
TEST_TIME 82 8930
TEST_TIME 83 8895
TEST_TIME 84 8865
TEST_TIME 85 8839
TEST_TIME 86 8854
TEST_TIME 87 8851
TEST_TIME 88 8817
TEST_TIME 89 8834
TEST_TIME 90 8889
TEST_TIME 91 8868
TEST_TIME 92 8954
TEST_TIME 93 8952
TEST_TIME 94 8964
TEST_TIME 95 9009
TEST_TIME 96 8981
TEST_TIME 97 8970
TEST_TIME 98 9104
TEST_TIME 99 9064
TEST_TIME 100 9085
TEST_TIME 101 9090
TEST_TIME 102 9017
TEST_TIME 103 9030
TEST_TIME 104 9005
TEST_TIME 105 8996
TEST_TIME 106 8963
TEST_TIME 107 8933
TEST_TIME 108 8921
TEST_TIME 109 8889
TEST_TIME 110 8919
TEST_TIME 111 8897
TEST_TIME 112 8905
TEST_TIME 113 8920
TEST_TIME 114 8923
TEST_TIME 115 8919
TEST_TIME 116 8924
TEST_TIME 117 8909
TEST_TIME 118 8930
TEST_TIME 119 8946
TEST_TIME 120 8922

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2021 01:39:01 to 24-10-2021 01:39:04.

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 1491021 1491021 0 0.00 0 20545 7 0 0
doc_list_biud 1491021 1491021 0 0.00 0 40922 29 0 0
sp_add_invoice_to_stock 5082 4963 119 2.34 30 220040 20064 0 119
sp_cancel_adding_invoice 5425 5425 0 0.00 4 80155 992 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 12134 362 0 0
sp_cancel_customer_reserve 531477 531477 0 0.00 1 40924 65 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 2 4936 88 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 6883 91 0 0
sp_cancel_supplier_invoice 5986 5980 6 0.10 2 15355 854 0 6
sp_cancel_supplier_order 404 404 0 0.00 8 1159076 107490 0 0
sp_cancel_write_off 9757 9757 0 0.00 1 6906 107 0 0
sp_client_order 21209 21209 0 0.00 3 39368 348 0 0
sp_customer_reserve 451670 451670 0 0.00 0 36483 201 0 0
sp_fill_shopping_cart 32395 32395 0 0.00 1 16699 46 0 0
sp_fill_shopping_cart_clo_res 451664 451664 0 0.00 0 22860 14 0 0
sp_get_clo_for_invoice 4957 4957 0 0.00 0 22888 304 0 0
sp_kill_cost_storno 16287 16287 0 0.00 0 8888 59 0 0
sp_kill_qstorno_ret_qs2qd 1079095 1079095 0 0.00 0 40919 35 0 0
sp_kill_qty_storno 570155 570155 0 0.00 0 40922 76 0 0
sp_lock_selected_doc 21195 21195 0 0.00 0 7077 5 0 0
sp_make_cost_storno 28544 28544 0 0.00 0 18151 54 0 0
sp_multiply_rows_for_pdistr 28544 28544 0 0.00 0 7864 31 0 0
sp_multiply_rows_for_qdistr 21209 21209 0 0.00 0 39312 164 0 0
sp_pay_from_customer 7257 7257 0 0.00 4 16277 126 0 0
sp_pay_to_supplier 6008 6008 0 0.00 5 21479 151 0 0
sp_payment_common 13265 13265 0 0.00 1 11175 28 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 8278 318 0 0
sp_qd_handle_on_invoice_upd_sts 10382 10382 0 0.00 0 32478 399 0 0
sp_qd_handle_on_reserve_upd_sts 10322 10322 0 0.00 0 11049 57 0 0
sp_reserve_write_off 10322 10322 0 0.00 6 16762 192 0 0
sp_supplier_invoice 5532 5532 0 0.00 7 104407 3863 0 0
sp_supplier_order 5665 5665 0 0.00 5 38628 1370 0 0
srv_aggregate_perf_data 105 104 1 0.95 2397 246310 20100 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1794 128831 43682 0 0
srv_find_qd_qs_mism 1023411 1023411 0 0.00 0 12697 6 0 0
srv_make_invnt_saldo 572 572 0 0.00 42 60823 1325 0 0
srv_make_money_saldo 927 927 0 0.00 0 1030 36 0 0
srv_recalc_idx_stat 3 3 0 0.00 172690 435611 312508 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4963 4963 0 0.00 0 11750 192 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 2126 90 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 4067 20 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 1438 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 2780 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 4920 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2997 28 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 31981 1574 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 1572 8 0 0
t$perf-norm:sp_client_order 21209 21209 0 0.00 0 6809 19 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 1592 7 0 0
t$perf-norm:sp_pay_from_customer 7257 7257 0 0.00 0 6452 12 0 0
t$perf-norm:sp_pay_to_supplier 6008 6008 0 0.00 0 4006 10 0 0
t$perf-norm:sp_reserve_write_off 10322 10322 0 0.00 0 7609 12 0 0
t$perf-norm:sp_supplier_invoice 5532 5532 0 0.00 0 4345 20 0 0
t$perf-norm:sp_supplier_order 5665 5665 0 0.00 0 2567 17 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 2376 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 185 6 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 863 24 0 0
t$perf-norm:srv_make_money_saldo 927 927 0 0.00 0 1771 20 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10152 9914 238 2.34 0 13084 24 0 238
v_all_customers 38248 38248 0 0.00 0 20 1 0 0
v_all_suppliers 30978 30978 0 0.00 0 33 1 0 0
v_all_wares 42418 42418 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 1387 10 0 0
v_cancel_client_order 3666 3666 0 0.00 0 11751 11 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 4930 9 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 7185 11 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 6869 15 0 0
v_cancel_supplier_invoice 854 842 12 1.40 0 2105 23 0 12
v_cancel_supplier_order 808 808 0 0.00 0 2293 9 0 0
v_cancel_write_off 2096 2096 0 0.00 0 3283 15 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 1161 2 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 2309 12 0 0
v_max_id_ord_sup 5527 5527 0 0.00 0 6798 7 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 216 3 0 0
v_max_non_paid_realizn 3918 3918 0 0.00 0 3518 6 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 15116 21 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 3170 9 0 0
v_min_id_ord_sup 5527 5527 0 0.00 0 6280 6 0 0
v_min_non_paid_invoice 6008 6008 0 0.00 0 7961 15 0 0
v_min_non_paid_realizn 7257 7257 0 0.00 0 11171 21 0 0
v_our_firm 10848 10848 0 0.00 0 26 1 0 0
v_reserve_write_off 20644 20644 0 0.00 0 16373 10 0 0
x_lock_depdocs_on_canc_invoice 10984 10984 0 0.00 0 21163 144 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 6340 414 0 0
x_make_qstorno_1000_1200 5665 5665 0 0.00 1 22592 1260 0 0
x_make_qstorno_1200_2000 5532 5532 0 0.00 3 102035 3712 0 0
x_make_qstorno_2100_3300 451670 451670 0 0.00 0 36482 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2021 01:39:05 to 24-10-2021 01:39:09.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:58 13 8192000000 8801764320 8814034944 6674320 0.081 3 1 2 0 6775248 58144 42784
23:08:59 43345 8192000000 9923185456 10239512576 1001146384 10.890 100 89 178 0 1030972400 6602384 23223632
23:10:45 29685 8192000000 9963007152 10292101120 1043267296 11.296 100 91 182 0 1075879776 9092480 23520000
23:12:18 16872 8192000000 10007871360 10348462080 1089430528 11.737 100 83 166 2 1120728160 10494560 20803072
23:13:40 14362 8192000000 10057350208 10407788544 1135202864 12.170 100 80 160 0 1164873856 10397440 19273552
23:15:00 8674 8192000000 10086728144 10450468864 1175479808 12.548 100 71 142 0 1202737456 10665936 16591712
23:16:12 5984 8192000000 10134393136 10505719808 1218949648 12.952 100 80 158 2 1249102048 12827776 17324624
23:17:19 2136 8192000000 10170401936 10548056064 1254512560 13.280 100 90 180 0 1288106128 13798880 19794688
23:18:22 1745 8192000000 10203120880 10584612864 1284210224 13.551 100 98 197 0 1320601104 14537408 21853472
23:19:24 2852 8192000000 10205795632 10596745216 1296153152 13.660 100 76 152 1 1325122800 11736560 17233088
23:20:31 3440 8192000000 10192541216 10602934272 1307071760 13.759 100 47 95 2 1324286480 4720720 12494000
23:21:36 40142 8192000000 10220146624 10621435904 1310389440 13.790 100 61 122 2 1331097184 4842688 15865056
23:23:18 3516 8192000000 10209690080 10627342336 1317407984 13.853 100 29 58 0 1330846560 6888048 6550528
23:24:24 6042 8192000000 10213642464 10632818688 1323695248 13.910 100 21 42 0 1334317760 5520224 5102288
23:25:33 75191 8192000000 10223887984 10646024192 1331049088 13.977 100 32 64 1 1343205808 4889392 7267328
23:27:51 5129 8192000000 10191392208 10601435136 1269210080 13.414 97 30 60 2 1281977152 5638528 7128544
23:28:58 8786 8192000000 10167622464 10570854400 1261779104 13.346 98 25 51 0 1273557440 6271040 5507296
23:30:08 68226 8192000000 10115316960 10517524480 1140444976 12.220 90 37 74 4 1154268912 4822016 9001920
23:32:17 3769 8192000000 10162798896 10562162688 1263184528 13.359 100 43 86 0 1281079984 7742992 10152464
23:33:24 6634 8192000000 10168053392 10574348288 1279857360 13.512 100 18 36 0 1289490496 5672368 3960768
23:34:34 11577 8192000000 10189818560 10594615296 1294604544 13.646 100 21 42 2 1305366464 6027920 4734000
23:35:48 7296 8192000000 10218173328 10607845376 1302102992 13.714 100 82 164 5 1327345392 4141200 21101200
23:36:55 5103 8192000000 10197544288 10613424128 1308864880 13.776 100 25 51 0 1320834880 5814496 6155504
23:38:03 19535 8192000000 10238449008 10650320896 1334201584 14.005 100 46 91 1 1350656224 4880800 11573840
23:39:28 72642 8192000000 10257270368 10673999872 1347013392 14.121 99 52 102 2 1367643920 9343040 11287488
23:41:54 76725 8192000000 10277390368 10714464256 1364419568 14.277 99 40 79 0 1386155264 12541392 9194304
23:44:20 1793 8192000000 10310940704 10730708992 1396780448 14.566 100 66 132 0 1427699120 14757920 16160752
23:45:24 14724 8192000000 10310994656 10737020928 1400731296 14.602 100 51 101 0 1428122800 14889696 12501808
23:46:44 16289 8192000000 10309457824 10738343936 1397716560 14.575 100 41 82 2 1424782320 16717328 10348432
23:48:03 13213 8192000000 10297787072 10719219712 1371558864 14.341 99 51 102 4 1403265648 18680416 13026368
23:49:18 5631 8192000000 10302158976 10717364224 1371768784 14.343 100 62 123 0 1411574896 25468896 14337216
23:50:37 2949 8192000000 10296387840 10697084928 1353637968 14.180 100 81 163 0 1401729328 28243904 19847456
23:51:43 9209 8192000000 10305728768 10716774400 1368302144 14.312 100 64 127 1 1408462944 26150000 14010800
23:53:05 4179 8192000000 10300782576 10704269312 1351383424 14.160 98 80 161 0 1398158080 27508480 19266176
23:54:13 5875 8192000000 10306865440 10716155904 1355902496 14.201 99 71 140 3 1403732848 30528992 17301360
23:55:25 4262 8192000000 10326890880 10736480256 1380423184 14.420 100 74 150 1 1432391888 33638960 18329744
23:56:37 1905 8192000000 10334774592 10732392448 1372196000 14.347 100 96 191 1 1434870672 39526768 23147904
23:57:44 4733 8192000000 10335707008 10745876480 1382955696 14.443 100 71 142 2 1438812656 38099472 17757488
23:58:53 1980 8192000000 10353640400 10759532544 1377387968 14.393 99 87 174 3 1445019536 45956256 21675312
00:00:00 9143 8192000000 10362949600 10777399296 1397182848 14.570 100 74 148 0 1466707344 50611056 18913440
00:01:16 2385 8192000000 10383720496 10796347392 1420197552 14.774 100 75 150 0 1486750896 47812224 18741120
00:02:24 2502 8192000000 10363063296 10776064000 1393013680 14.533 99 73 141 1 1454859360 43716992 18128688
00:03:29 6437 8192000000 10363241856 10802057216 1408378688 14.670 100 36 72 2 1471263376 53026672 9858016
00:04:49 7303 8192000000 10351729200 10787528704 1407475296 14.662 100 36 70 0 1459352976 41135808 10741872
00:06:20 20588 8192000000 10337738864 10774872064 1396667920 14.565 100 21 42 0 1441294720 38375504 6251296
00:07:47 15455 8192000000 10332705024 10772623360 1399425248 14.590 100 26 52 1 1443907648 37545888 6936512
00:09:13 14330 8192000000 10331961616 10763411456 1395250704 14.553 100 32 64 3 1429499824 25651856 8597264
00:10:31 62883 8192000000 10304705040 10751991808 1371726464 14.343 98 28 55 0 1390880384 13250592 5903328
00:12:35 46517 8192000000 10317312512 10763706368 1392802640 14.531 100 40 79 0 1415578448 13191968 9583840
00:14:39 51963 8192000000 10292795232 10752266240 1376224400 14.383 99 16 33 1 1396949248 16719184 4005664
00:17:11 75235 8192000000 10294789392 10750345216 1382309936 14.437 99 14 28 0 1393930320 8800688 2819696
00:19:31 59052 8192000000 10309080560 10764677120 1405403008 14.643 100 31 63 2 1422404592 9458080 7543504
00:21:30 66237 8192000000 10301186688 10771152896 1402386336 14.616 100 10 20 0 1418587888 12942384 3259168
00:23:44 128818 8192000000 10300471712 10760208384 1380336592 14.420 98 25 49 1 1392231840 6010976 5884272
00:27:02 84325 8192000000 10294240768 10756857856 1361264368 14.249 97 15 29 1 1377633952 13274176 3095408
00:29:27 51745 8192000000 10311919280 10758467584 1385181280 14.463 99 45 88 2 1406715856 10486336 11048240
00:31:24 67531 8192000000 10296443616 10774437888 1400471536 14.599 100 14 28 1 1419561568 15080576 4009456
00:33:32 63608 8192000000 10284538656 10760163328 1387031440 14.479 99 7 15 0 1398208736 9250384 1926912
00:36:11 61163 8192000000 10280064784 10747445248 1386278208 14.473 99 12 24 0 1398633184 9453488 2901488
00:38:24 69482 8192000000 10283940784 10745303040 1354382064 14.187 97 12 24 0 1366878672 9890928 2605680
00:40:35 73452 8192000000 10294263664 10755170304 1374785632 14.370 98 12 24 0 1396711248 19111120 2814496
00:42:50 70789 8192000000 10295577824 10749415424 1354854912 14.191 97 15 30 1 1368228384 10145264 3228208
00:45:07 17139 8192000000 10284702432 10716508160 1361186640 14.248 99 56 108 0 1383793568 9700032 12906896
00:46:26 54121 8192000000 10267733232 10705424384 1289939040 13.604 94 28 56 1 1313036560 16768320 6329200
00:48:21 88260 8192000000 10258593344 10711068672 1314519024 13.827 97 9 18 0 1331143200 14501136 2123040
00:50:52 72314 8192000000 10258292064 10700886016 1292509776 13.627 95 21 42 0 1305833632 8703056 4620800
00:53:08 68953 8192000000 10250537536 10697633792 1339687616 14.055 99 25 50 1 1361917808 16915632 5314560
00:55:37 54439 8192000000 10265868384 10709733376 1346165680 14.113 99 45 90 0 1369517152 13650688 9700784
00:57:37 72877 8192000000 10246859376 10711629824 1336855664 14.029 98 11 22 0 1347521648 7912512 2753472
00:59:50 63806 8192000000 10264298752 10720550912 1367602032 14.306 100 12 24 0 1380506496 10146688 2757776
01:02:15 59349 8192000000 10270196592 10732601344 1374521184 14.368 100 15 30 0 1386836656 9043824 3271648
01:04:15 57856 8192000000 10280637472 10742419456 1365551808 14.287 99 14 28 0 1379292496 10467696 3272992
01:06:44 92530 8192000000 10295541040 10749231104 1335710768 14.019 96 15 29 0 1353529120 14847072 2971280
01:09:20 43075 8192000000 10294589808 10748915712 1383967568 14.452 100 21 42 0 1397840560 9518576 4354416
01:11:04 54056 8192000000 10294392880 10744000512 1360404000 14.241 98 34 69 0 1381616528 13848656 7363872
01:13:02 57055 8192000000 10295415408 10744020992 1372417280 14.349 99 9 18 1 1382615184 8255824 1942080
01:14:59 60680 8192000000 10289527216 10764275712 1364174336 14.275 98 17 34 1 1387677056 19647776 3854944
01:17:06 38072 8192000000 10294844352 10754158592 1372170224 14.346 99 14 28 1 1385659536 10483184 3006128
01:18:45 77954 8192000000 10293452880 10741882880 1380546144 14.421 100 15 30 0 1399611664 15750048 3315472
01:21:48 34560 8192000000 10272856944 10732519424 1365192304 14.284 99 7 13 0 1376664112 10015088 1456720
01:25:46 73416 8192000000 10286177200 10750644224 1380639968 14.422 100 9 18 0 1398714624 15823200 2251456
01:28:01 75682 8192000000 10290516752 10751815680 1389626352 14.503 100 11 22 1 1406938528 14757392 2554784
01:30:19 79583 8192000000 10292565264 10750095360 1356893392 14.209 97 20 40 0 1370459568 9000400 4565776
01:32:40 18519 8192000000 10289019648 10738192384 1370583424 14.332 99 34 70 0 1386402448 7940288 7878736
01:34:06 33559 8192000000 10276146032 10722828288 1331268416 13.979 97 29 55 2 1346594112 9076160 6249536
01:35:40 31574 8192000000 10288272064 10736136192 1349678656 14.145 98 22 43 0 1365614128 11163328 4772144
01:37:14 49482 8192000000 9489839936 9811783680 622184752 7.058 45 8 15 0 640018160 16333936 1499472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 119 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2021 01:39:27 to 24-10-2021 01:39:29.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244465
MON$OLDEST_ACTIVE               244940
MON$OLDEST_SNAPSHOT             244940
MON$NEXT_TRANSACTION            244940
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2048160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:121: 
:122: mscs-test-161.budzdorov.ru    Sun Oct 24 01:38:12 2021
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Sun Oct 24 01:38:12 2021
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Sun Oct 24 01:38:23 2021
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:132: 
--- end of diff output ---

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

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