Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.08.2021 16: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 16432676 kB
MemAvailable 30925852 kB
Buffers 0 kB
Cached 15229352 kB
SwapCached 1328 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-08-24 16:37:48
DTS_END                         2021-08-24 16:37:48.3090
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(57:17)
IP                              ::1/60204
TRN_ID                          245354
ATT_ID                          661
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: 9098.52 18084 1091822
customer order: creation 182.03 370 21844
customer order: refuse 14.81 363 1777
order to supplier: creation 48.53 1322 5824
order to supplier: removal 3.50 92237 420
invoice (draft): creation 48.00 3595 5760
invoice (draft): removal 49.06 801 5887
invoice accept: apply 43.85 18254 5262
invoice accept: cancel 44.37 844 5324
customer reserve: creation 3956.03 184 474723
customer reserve: removal 4401.40 57 528168
realization accept: apply 88.08 183 10569
realization accept: cancel 82.37 101 9884
payment from customer: creation 61.86 124 7423
payment from customer: removal 5.83 127 699
payment to supplier: creation 51.66 129 6199
payment to supplier: removal 3.68 81 441
service: total inventory turnovers 5.29 1067 635
service: total monetary turnovers 7.20 25 864
service: aggregate perf. data 0.96 18158 115
service: refresh index statistics 0.03 223665 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-08-2021 16:38:55 to 24-08-2021 16:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2348
TEST_TIME 2 2592
TEST_TIME 3 3100
TEST_TIME 4 3329
TEST_TIME 5 4087
TEST_TIME 6 4898
TEST_TIME 7 5096
TEST_TIME 8 5437
TEST_TIME 9 5418
TEST_TIME 10 5773
TEST_TIME 11 5962
TEST_TIME 12 6425
TEST_TIME 13 6649
TEST_TIME 14 6986
TEST_TIME 15 7539
TEST_TIME 16 7700
TEST_TIME 17 7857
TEST_TIME 18 8047
TEST_TIME 19 7981
TEST_TIME 20 8102
TEST_TIME 21 8381
TEST_TIME 22 8628
TEST_TIME 23 8865
TEST_TIME 24 9025
TEST_TIME 25 9633
TEST_TIME 26 9992
TEST_TIME 27 10322
TEST_TIME 28 10328
TEST_TIME 29 10302
TEST_TIME 30 10036
TEST_TIME 31 9828
TEST_TIME 32 9953
TEST_TIME 33 9820
TEST_TIME 34 9603
TEST_TIME 35 9495
TEST_TIME 36 9465
TEST_TIME 37 9521
TEST_TIME 38 9458
TEST_TIME 39 9379
TEST_TIME 40 9421
TEST_TIME 41 9255
TEST_TIME 42 9183
TEST_TIME 43 9224
TEST_TIME 44 9120
TEST_TIME 45 9052
TEST_TIME 46 9047
TEST_TIME 47 8993
TEST_TIME 48 8970
TEST_TIME 49 8859
TEST_TIME 50 8791
TEST_TIME 51 8827
TEST_TIME 52 8846
TEST_TIME 53 8838
TEST_TIME 54 8827
TEST_TIME 55 8940
TEST_TIME 56 8938
TEST_TIME 57 8942
TEST_TIME 58 8891
TEST_TIME 59 9028
TEST_TIME 60 8991
TEST_TIME 61 9129
TEST_TIME 62 9165
TEST_TIME 63 9190
TEST_TIME 64 9119
TEST_TIME 65 9054
TEST_TIME 66 9035
TEST_TIME 67 9014
TEST_TIME 68 8988
TEST_TIME 69 8970
TEST_TIME 70 8900
TEST_TIME 71 8846
TEST_TIME 72 8751
TEST_TIME 73 8769
TEST_TIME 74 8893
TEST_TIME 75 8994
TEST_TIME 76 8975
TEST_TIME 77 8984
TEST_TIME 78 8980
TEST_TIME 79 9066
TEST_TIME 80 9119
TEST_TIME 81 9101
TEST_TIME 82 9142
TEST_TIME 83 9184
TEST_TIME 84 9206
TEST_TIME 85 9184
TEST_TIME 86 9201
TEST_TIME 87 9116
TEST_TIME 88 9089
TEST_TIME 89 9105
TEST_TIME 90 9113
TEST_TIME 91 9052
TEST_TIME 92 9015
TEST_TIME 93 9004
TEST_TIME 94 8984
TEST_TIME 95 9023
TEST_TIME 96 9015
TEST_TIME 97 9030
TEST_TIME 98 9083
TEST_TIME 99 9100
TEST_TIME 100 9149
TEST_TIME 101 9199
TEST_TIME 102 9168
TEST_TIME 103 9210
TEST_TIME 104 9187
TEST_TIME 105 9190
TEST_TIME 106 9297
TEST_TIME 107 9346
TEST_TIME 108 9313
TEST_TIME 109 9241
TEST_TIME 110 9204
TEST_TIME 111 9203
TEST_TIME 112 9159
TEST_TIME 113 9136
TEST_TIME 114 9133
TEST_TIME 115 9073
TEST_TIME 116 9106
TEST_TIME 117 9136
TEST_TIME 118 9132
TEST_TIME 119 9137
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-08-2021 16:38:59 to 24-08-2021 16:39:02.

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 1531525 1531525 0 0.00 0 22888 7 0 0
doc_list_biud 1531522 1531522 0 0.00 0 32120 25 0 0
sp_add_invoice_to_stock 5430 5262 168 3.09 32 247644 18255 0 168
sp_cancel_adding_invoice 5324 5324 0 0.00 5 65778 844 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 13839 364 0 0
sp_cancel_customer_reserve 528168 528168 0 0.00 1 26259 57 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 23576 128 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 5259 82 0 0
sp_cancel_supplier_invoice 5903 5887 16 0.27 2 30209 802 0 16
sp_cancel_supplier_order 420 420 0 0.00 6 843168 92238 0 0
sp_cancel_write_off 9884 9884 0 0.00 1 25074 102 0 0
sp_client_order 21844 21844 0 0.00 4 30729 371 0 0
sp_customer_reserve 474723 474723 0 0.00 0 35749 185 0 0
sp_fill_shopping_cart 33421 33421 0 0.00 1 12919 42 0 0
sp_fill_shopping_cart_clo_res 474709 474709 0 0.00 0 15108 14 0 0
sp_get_clo_for_invoice 5248 5248 0 0.00 0 25033 324 0 0
sp_kill_cost_storno 16348 16348 0 0.00 0 8338 54 0 0
sp_kill_qstorno_ret_qs2qd 1072527 1072527 0 0.00 0 30197 31 0 0
sp_kill_qty_storno 567277 567277 0 0.00 0 32119 68 0 0
sp_lock_selected_doc 21760 21760 0 0.00 0 10848 5 0 0
sp_make_cost_storno 29439 29439 0 0.00 0 13539 53 0 0
sp_multiply_rows_for_pdistr 29439 29439 0 0.00 0 15648 31 0 0
sp_multiply_rows_for_qdistr 21844 21844 0 0.00 0 15222 167 0 0
sp_pay_from_customer 7423 7423 0 0.00 4 15662 125 0 0
sp_pay_to_supplier 6199 6199 0 0.00 4 17208 130 0 0
sp_payment_common 13622 13622 0 0.00 1 8503 29 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 13694 325 0 0
sp_qd_handle_on_invoice_upd_sts 10572 10572 0 0.00 0 32119 343 0 0
sp_qd_handle_on_reserve_upd_sts 10569 10569 0 0.00 0 21858 62 0 0
sp_reserve_write_off 10569 10569 0 0.00 6 21889 184 0 0
sp_supplier_invoice 5760 5760 0 0.00 7 86793 3595 0 0
sp_supplier_order 5824 5824 0 0.00 5 47152 1323 0 0
srv_aggregate_perf_data 115 115 0 0.00 2766 191628 18158 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1653 163890 45171 0 0
srv_find_qd_qs_mism 1042026 1042026 0 0.00 0 22886 6 0 0
srv_make_invnt_saldo 635 635 0 0.00 37 85308 1068 0 0
srv_make_money_saldo 864 864 0 0.00 0 3570 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 171013 354403 223665 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5263 5263 0 0.00 0 23008 177 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 2979 91 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 5094 18 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 1225 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 3371 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 10406 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 3328 19 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 18284 1483 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 1528 6 0 0
t$perf-norm:sp_client_order 21844 21844 0 0.00 0 9170 18 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 2988 8 0 0
t$perf-norm:sp_pay_from_customer 7424 7424 0 0.00 0 15346 12 0 0
t$perf-norm:sp_pay_to_supplier 6199 6199 0 0.00 0 10214 9 0 0
t$perf-norm:sp_reserve_write_off 10570 10570 0 0.00 0 11550 11 0 0
t$perf-norm:sp_supplier_invoice 5760 5760 0 0.00 0 11396 20 0 0
t$perf-norm:sp_supplier_order 5824 5824 0 0.00 0 12838 19 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 319 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 470 8 0 0
t$perf-norm:srv_make_invnt_saldo 635 635 0 0.00 0 1849 21 0 0
t$perf-norm:srv_make_money_saldo 864 864 0 0.00 0 1256 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10832 10496 336 3.10 0 14327 20 0 336
v_all_customers 39416 39416 0 0.00 0 26 1 0 0
v_all_suppliers 32152 32152 0 0.00 0 111 1 0 0
v_all_wares 43688 43688 0 0.00 0 51 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 8340 28 0 0
v_cancel_client_order 3554 3554 0 0.00 0 5929 8 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 23535 23 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 1283 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 5221 14 0 0
v_cancel_supplier_invoice 890 858 32 3.59 0 5431 25 0 32
v_cancel_supplier_order 840 840 0 0.00 0 22967 31 0 0
v_cancel_write_off 2098 2098 0 0.00 0 24934 25 0 0
v_max_id_clo_ord 5824 5824 0 0.00 0 4005 2 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 8634 29 0 0
v_max_id_ord_sup 5753 5753 0 0.00 0 4987 5 0 0
v_max_non_paid_invoice 1700 1700 0 0.00 0 629 3 0 0
v_max_non_paid_realizn 4169 4169 0 0.00 0 5216 6 0 0
v_min_id_clo_ord 5824 5824 0 0.00 0 2984 7 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 7343 16 0 0
v_min_id_ord_sup 5753 5753 0 0.00 0 6423 8 0 0
v_min_non_paid_invoice 6199 6199 0 0.00 0 7665 16 0 0
v_min_non_paid_realizn 7423 7423 0 0.00 0 7761 18 0 0
v_our_firm 10780 10780 0 0.00 0 21 1 0 0
v_reserve_write_off 21138 21138 0 0.00 0 8344 9 0 0
x_lock_depdocs_on_canc_invoice 10782 10782 0 0.00 0 18994 129 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 5255 315 0 0
x_make_qstorno_1000_1200 5824 5824 0 0.00 1 47114 1240 0 0
x_make_qstorno_1200_2000 5760 5760 0 0.00 3 81649 3434 0 0
x_make_qstorno_2100_3300 474723 474723 0 0.00 0 35746 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-08-2021 16:39:03 to 24-08-2021 16:39:07.

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
14:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:53 17453 8192000000 10358770304 10675097600 1410748528 14.691 100 86 172 0 1444639008 5479152 28411328
14:10:17 22342 8192000000 10420586176 10746863616 1473061360 15.241 100 93 186 0 1512120160 8851376 30207424
14:11:45 20828 8192000000 10464134624 10805239808 1520967360 15.659 100 81 162 1 1555910784 9609712 25333712
14:13:15 12415 8192000000 10579817360 10937872384 1633634592 16.626 100 77 154 1 1667325008 11071616 22618800
14:14:34 7570 8192000000 10647304960 11015417856 1703003728 17.210 100 77 154 0 1738740944 13758032 21979184
14:15:46 5356 8192000000 10703892752 11083350016 1766925360 17.742 100 80 159 0 1803076816 13792592 22358864
14:16:53 7126 8192000000 10740162576 11129233408 1806523504 18.067 100 74 148 0 1840000000 13467664 20008832
14:18:05 2122 8192000000 10811836048 11198656512 1863698384 18.533 100 88 176 2 1902039184 13416512 24924288
14:19:09 1612 8192000000 10835219952 11224424448 1885733296 18.711 100 94 190 0 1926948992 14586720 26628976
14:20:11 5460 8192000000 10811861600 11227193344 1889494720 18.742 100 47 94 3 1911604736 7977120 14132896
14:21:18 11282 8192000000 10808105888 11234881536 1894988048 18.786 100 22 44 1 1907560768 6352672 6220048
14:22:31 12428 8192000000 10820007888 11250360320 1908709520 18.896 100 41 82 4 1926858464 6489568 11659376
14:23:49 15220 8192000000 10822038064 11256516608 1906799312 18.881 100 26 52 1 1920639584 6761520 7078752
14:25:07 7582 8192000000 10823078976 11263635456 1915062272 18.947 100 24 48 2 1928314160 6350512 6901376
14:26:18 11672 8192000000 10833670768 11267141632 1916055888 18.955 100 18 33 1 1927128240 5283936 5788416
14:27:30 11988 8192000000 10791301984 11225116672 1870017920 18.584 99 16 32 0 1880286832 5855184 4413728
14:28:43 11709 8192000000 10839200224 11236290560 1882479344 18.685 100 70 136 4 1914230640 10791936 20959360
14:29:55 6603 8192000000 10745729744 11167911936 1792843536 17.955 97 24 48 0 1805320256 6015008 6461712
14:31:08 8948 8192000000 10703083792 11121934336 1787570096 17.912 100 31 62 2 1803375760 6913616 8892048
14:32:17 38713 8192000000 10716381728 11140198400 1751805968 17.617 96 18 37 0 1762457984 4720000 5932016
14:33:57 7750 8192000000 10738965808 11157602304 1808649664 18.085 99 30 60 1 1823254208 6428272 8176272
14:35:07 7819 8192000000 10774466592 11206815744 1863910464 18.535 100 18 36 2 1874268336 5171904 5185968
14:36:16 8864 8192000000 10787256960 11223707648 1878861696 18.656 100 12 23 0 1887534000 5217776 3454528
14:37:25 99872 8192000000 10846734256 11277287424 1911095568 18.915 100 26 52 0 1923395296 5057392 7242336
14:40:14 19494 8192000000 10897941504 11323543552 1958689200 19.296 100 53 106 0 1983194016 9297056 15207760
14:41:36 30851 8192000000 10904106480 11338350592 1971386336 19.396 100 47 94 2 1998051568 12816688 13848544
14:43:15 13846 8192000000 10901356640 11347156992 1979042528 19.457 100 48 95 4 2004502832 10786464 14673840
14:44:37 5513 8192000000 10941775040 11372933120 2000280192 19.625 100 58 114 2 2029261312 11325552 17655568
14:45:46 9617 8192000000 10937818816 11377946624 2004804784 19.661 100 55 110 2 2036326464 15584880 15936800
14:47:02 10315 8192000000 10962753600 11396964352 2014613824 19.738 100 61 122 0 2051251776 18640016 17997936
14:48:17 8803 8192000000 10959475504 11385614336 1998655344 19.612 99 69 138 1 2042526720 23708192 20163184
14:49:30 3333 8192000000 10965218096 11389014016 2006835056 19.677 100 75 150 3 2052779728 23462464 22482208
14:50:37 2450 8192000000 10959314784 11373350912 1978700992 19.454 99 74 149 2 2029091248 26879984 23510272
14:51:43 1653 8192000000 10963582704 11379732480 1985484656 19.508 100 86 171 1 2044927264 32413664 27028944
14:52:48 9661 8192000000 10948582912 11379019776 1983185360 19.490 100 59 117 1 2032150672 30936080 18029232
14:54:04 2223 8192000000 10917522496 11341471744 1950062416 19.227 100 66 132 3 1999737040 29392672 20281952
14:55:25 2147 8192000000 10966928208 11377676288 1983237856 19.490 100 84 168 0 2041027280 31552480 26236944
14:56:32 2105 8192000000 10956711376 11367170048 1979406128 19.460 100 73 144 5 2031346672 29794816 22145728
14:57:39 2828 8192000000 10942016048 11360395264 1960326896 19.309 99 73 146 1 2023146752 39920160 22899696
14:58:47 8687 8192000000 10936104608 11365515264 1964155040 19.339 100 45 89 2 2013835392 35287344 14393008
15:00:03 10262 8192000000 10933729360 11376058368 1950802064 19.233 99 27 54 0 1995677056 35341872 9533120
15:01:16 14532 8192000000 10905604208 11354107904 1945756016 19.193 99 30 60 1 1988279888 32912816 9611056
15:02:31 22439 8192000000 10889422944 11344474112 1941401056 19.158 100 17 34 0 1971314720 24182672 5730992
15:03:55 27654 8192000000 10896866288 11346571264 1931769392 19.081 99 14 28 0 1954144624 18392752 3982480
15:05:24 34757 8192000000 10888667360 11324477440 1908625536 18.896 98 41 81 3 1937971040 17007488 12338016
15:07:00 87885 8192000000 10861038976 11302895616 1889537904 18.742 97 33 65 1 1912635392 13714864 9382624
15:09:29 27555 8192000000 10879580320 11350032384 1951289808 19.237 100 9 18 1 1969491616 16124784 2077024
15:10:57 101053 8192000000 10903919312 11360067584 1929056720 19.059 98 22 44 1 1949007008 14439936 5510352
15:13:39 60516 8192000000 10899830160 11366350848 1950829376 19.233 99 12 24 0 1977151008 22268096 4053536
15:16:23 53784 8192000000 10930230368 11377426432 1985403984 19.507 100 34 65 2 2010286288 15166512 9715792
15:18:43 30411 8192000000 10923960304 11365158912 1982172000 19.482 100 34 68 1 1999269664 7799904 9297760
15:20:15 28077 8192000000 10916176912 11384893440 1992971344 19.567 100 39 78 1 2012431824 8100720 11359760
15:21:48 60114 8192000000 10921073184 11389857792 2000758560 19.629 100 22 44 0 2020378976 13513376 6107040
15:23:49 38056 8192000000 10915603776 11405328384 1998239904 19.609 100 6 12 0 2013622992 13621552 1761536
15:25:27 50677 8192000000 10918585040 11397263360 1978534864 19.453 99 14 28 0 1997739888 15051776 4153248
15:27:29 64551 8192000000 10906184656 11398033408 2000933824 19.630 100 17 34 1 2015687520 9717824 5035872
15:29:37 22990 8192000000 10927668752 11401261056 2003704576 19.652 100 21 42 1 2017100160 7163200 6232384
15:31:22 58711 8192000000 10931129536 11414753280 1986764992 19.518 99 8 16 0 2001767968 12898064 2104912
15:33:48 64006 8192000000 10936933456 11404918784 1985136768 19.505 99 28 56 3 2004777248 11838512 7801968
15:36:27 24404 8192000000 10922577184 11390844928 1995516624 19.587 100 32 64 1 2015211872 10708576 8986672
15:38:02 71896 8192000000 10905343664 11381186560 1905613584 18.871 95 22 44 1 1922733168 11353792 5765792
15:40:22 54236 8192000000 10918528896 11361759232 1880436704 18.669 94 24 48 1 1895231760 8240848 6554208
15:42:19 87978 8192000000 10859016080 11327270912 1880956448 18.673 96 26 52 1 1897009792 8974768 7078576
15:45:02 32156 8192000000 10849445984 11316953088 1929112832 19.060 100 23 46 0 1944497936 8826688 6558416
15:46:34 68361 8192000000 10848219296 11319504896 1887725744 18.727 97 13 27 1 1902239216 11175968 3337504
15:48:43 98149 8192000000 10880101056 11346690048 1932423648 19.086 99 16 32 1 1956785136 19463472 4898016
15:51:33 45341 8192000000 10864575920 11337740288 1886727728 18.719 97 10 20 0 1916190912 26373216 3089968
15:53:22 19201 8192000000 10910320304 11347750912 1923512240 19.015 98 56 111 1 1946629408 7082656 16034512
15:54:44 35543 8192000000 10910483648 11348910080 1913756128 18.937 98 35 70 1 1935110384 11877328 9476928
15:57:10 163889 8192000000 10902101504 11371376640 1793042816 17.957 91 18 37 0 1814921488 17070864 4807808
16:00:54 71655 8192000000 10922851968 11355639808 1922926544 19.010 98 34 67 2 1943836224 11350176 9559504
16:03:07 58039 8192000000 10874337936 11347353600 1941754624 19.161 100 12 24 0 1959816288 14767056 3294608
16:05:21 94595 8192000000 10898227056 11365720064 1946511952 19.199 99 22 45 0 1964475040 11590992 6372096
16:07:57 65336 8192000000 10891314496 11368640512 1965587232 19.350 99 9 18 0 1975497568 7532688 2377648
16:10:09 56179 8192000000 10913461504 11386081280 1929345952 19.062 97 11 22 2 1937983056 5635168 3001936
16:12:14 64380 8192000000 10914764784 11390103552 1963555680 19.334 98 19 38 0 1981633328 12568688 5508960
16:14:18 39341 8192000000 10941862288 11405557760 2001195168 19.632 100 20 41 1 2019058816 12065792 5797856
16:15:58 64553 8192000000 10918937808 11390611456 1948644800 19.216 98 19 38 2 1970595664 16411472 5539392
16:18:06 61165 8192000000 10906253888 11384057856 1936223776 19.117 97 10 20 0 1951315520 12763536 2328208
16:20:08 29607 8192000000 10886524672 11350175744 1918957088 18.978 98 36 71 2 1943173056 14211232 10004736
16:21:49 47299 8192000000 10903281024 11374243840 1936519872 19.119 98 25 50 0 1968259088 24570336 7168880
16:23:36 82763 8192000000 10897921824 11383242752 1910337072 18.909 97 17 34 0 1932152784 16776144 5039568
16:25:59 86717 8192000000 10870067552 11321909248 1909750176 18.905 97 32 64 0 1928038864 8309200 9979488
16:28:27 75577 8192000000 10878519024 11360206848 1898572112 18.815 97 15 29 0 1922168816 19939296 3657408
16:30:43 83219 8192000000 10880302512 11359727616 1866305776 18.554 95 16 31 3 1896846176 26617568 3922832
16:33:08 68856 8192000000 10892071440 11355049984 1943632576 19.176 100 13 26 0 1959756416 12273888 3849952
16:35:20 58084 8192000000 10881384960 11346436096 1912608112 18.928 97 17 34 0 1926484208 9384000 4492096
16:37:21 36087 8192000000 9261782352 9527431168 419119568 4.867 22 2 4 0 426147120 6611968 415584

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-08-2021 16:39:09 to 24-08-2021 16:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-08-2021 16:39:25 to 24-08-2021 16:39:27.

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          245899
MON$OLDEST_ACTIVE               245900
MON$OLDEST_SNAPSHOT             245900
MON$NEXT_TRANSACTION            245900
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                       2005696
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:410: 
:411: mscs-test-161.budzdorov.ru    Tue Aug 24 16:38:00 2021
:412:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:413: 
:414: 
:415: mscs-test-161.budzdorov.ru    Tue Aug 24 16:38:05 2021
:416:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:417: 
:418: 
:419: mscs-test-161.budzdorov.ru    Tue Aug 24 16:38:05 2021
:420:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:421: 
--- end of diff output ---

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

24.08.21 16:39:28. 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-08-24 16:39:30 No severe PSQL-related problems occured
24.08.21 16:39:32. End of report.