Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.2021 13:28
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 16323372 kB
MemAvailable 31146340 kB
Buffers 0 kB
Cached 15382400 kB
SwapCached 3860 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-02-24 13:26:56
DTS_END                         2021-02-24 13:26:56.4750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36518
TRN_ID                          233385
ATT_ID                          670
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: 8868.36 26890 1064203
customer order: creation 163.81 403 19657
customer order: refuse 14.42 375 1730
order to supplier: creation 43.98 1430 5277
order to supplier: removal 3.36 133298 403
invoice (draft): creation 43.99 3836 5279
invoice (draft): removal 51.33 1040 6159
invoice accept: apply 39.40 19356 4728
invoice accept: cancel 46.08 917 5530
customer reserve: creation 3659.78 186 439174
customer reserve: removal 4517.33 77 542079
realization accept: apply 81.04 198 9725
realization accept: cancel 80.00 131 9600
payment from customer: creation 57.22 134 6866
payment from customer: removal 5.53 119 663
payment to supplier: creation 47.26 147 5671
payment to supplier: removal 3.38 85 405
service: total inventory turnovers 3.77 4079 452
service: total monetary turnovers 5.76 668 691
service: aggregate perf. data 0.91 13926 109
service: refresh index statistics 0.04 357401 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-02-2021 13:28:46 to 24-02-2021 13:28:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2014
TEST_TIME 2 1502
TEST_TIME 3 2287
TEST_TIME 4 2797
TEST_TIME 5 2710
TEST_TIME 6 3800
TEST_TIME 7 4340
TEST_TIME 8 4598
TEST_TIME 9 5117
TEST_TIME 10 5493
TEST_TIME 11 5528
TEST_TIME 12 6082
TEST_TIME 13 6107
TEST_TIME 14 6283
TEST_TIME 15 6596
TEST_TIME 16 7076
TEST_TIME 17 7162
TEST_TIME 18 7186
TEST_TIME 19 7352
TEST_TIME 20 7504
TEST_TIME 21 7545
TEST_TIME 22 7721
TEST_TIME 23 7656
TEST_TIME 24 8038
TEST_TIME 25 8149
TEST_TIME 26 8327
TEST_TIME 27 8990
TEST_TIME 28 8899
TEST_TIME 29 9765
TEST_TIME 30 11221
TEST_TIME 31 11216
TEST_TIME 32 11045
TEST_TIME 33 10820
TEST_TIME 34 10790
TEST_TIME 35 10631
TEST_TIME 36 10723
TEST_TIME 37 10512
TEST_TIME 38 10328
TEST_TIME 39 10117
TEST_TIME 40 10085
TEST_TIME 41 10014
TEST_TIME 42 9979
TEST_TIME 43 9912
TEST_TIME 44 9921
TEST_TIME 45 9794
TEST_TIME 46 9677
TEST_TIME 47 9610
TEST_TIME 48 9505
TEST_TIME 49 9457
TEST_TIME 50 9595
TEST_TIME 51 9723
TEST_TIME 52 9706
TEST_TIME 53 9595
TEST_TIME 54 9792
TEST_TIME 55 9782
TEST_TIME 56 9736
TEST_TIME 57 9712
TEST_TIME 58 9621
TEST_TIME 59 9739
TEST_TIME 60 9810
TEST_TIME 61 9753
TEST_TIME 62 9599
TEST_TIME 63 9529
TEST_TIME 64 9448
TEST_TIME 65 9329
TEST_TIME 66 9300
TEST_TIME 67 9182
TEST_TIME 68 9254
TEST_TIME 69 9191
TEST_TIME 70 9168
TEST_TIME 71 9194
TEST_TIME 72 9186
TEST_TIME 73 9168
TEST_TIME 74 9171
TEST_TIME 75 9172
TEST_TIME 76 9193
TEST_TIME 77 9190
TEST_TIME 78 9244
TEST_TIME 79 9378
TEST_TIME 80 9381
TEST_TIME 81 9415
TEST_TIME 82 9380
TEST_TIME 83 9275
TEST_TIME 84 9225
TEST_TIME 85 9146
TEST_TIME 86 9069
TEST_TIME 87 9063
TEST_TIME 88 9038
TEST_TIME 89 9057
TEST_TIME 90 9026
TEST_TIME 91 9006
TEST_TIME 92 9020
TEST_TIME 93 8996
TEST_TIME 94 9000
TEST_TIME 95 9055
TEST_TIME 96 9069
TEST_TIME 97 9146
TEST_TIME 98 9149
TEST_TIME 99 9194
TEST_TIME 100 9119
TEST_TIME 101 9086
TEST_TIME 102 9086
TEST_TIME 103 9060
TEST_TIME 104 9027
TEST_TIME 105 8998
TEST_TIME 106 9050
TEST_TIME 107 9007
TEST_TIME 108 9004
TEST_TIME 109 9038
TEST_TIME 110 9019
TEST_TIME 111 9011
TEST_TIME 112 9016
TEST_TIME 113 9015
TEST_TIME 114 9132
TEST_TIME 115 9104
TEST_TIME 116 9033
TEST_TIME 117 8986
TEST_TIME 118 8956
TEST_TIME 119 8910
TEST_TIME 120 8870

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-02-2021 13:28:50 to 24-02-2021 13:28:53.

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 1472343 1472343 0 0.00 0 20913 7 0 0
doc_list_biud 1472331 1472331 0 0.00 0 32850 36 0 0
sp_add_invoice_to_stock 4851 4728 123 2.53 34 186262 19357 0 123
sp_cancel_adding_invoice 5530 5530 0 0.00 4 72846 917 0 0
sp_cancel_client_order 1730 1730 0 0.00 5 13120 375 0 0
sp_cancel_customer_reserve 542079 542079 0 0.00 1 28512 78 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 12896 120 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4205 86 0 0
sp_cancel_supplier_invoice 6170 6159 11 0.17 1 18988 1040 0 11
sp_cancel_supplier_order 403 403 0 0.00 13 1258616 133298 0 0
sp_cancel_write_off 9600 9600 0 0.00 1 9966 132 0 0
sp_client_order 19657 19657 0 0.00 4 45087 404 0 0
sp_customer_reserve 439174 439174 0 0.00 0 75621 186 0 0
sp_fill_shopping_cart 30210 30210 0 0.00 1 16261 49 0 0
sp_fill_shopping_cart_clo_res 439167 439167 0 0.00 0 18542 19 0 0
sp_get_clo_for_invoice 4720 4720 0 0.00 0 57886 454 0 0
sp_kill_cost_storno 16198 16198 0 0.00 0 7368 63 0 0
sp_kill_qstorno_ret_qs2qd 1100320 1100320 0 0.00 0 28494 42 0 0
sp_kill_qty_storno 579935 579935 0 0.00 0 32850 89 0 0
sp_lock_selected_doc 20000 20000 0 0.00 0 12892 8 0 0
sp_make_cost_storno 26982 26982 0 0.00 0 20905 71 0 0
sp_multiply_rows_for_pdistr 26977 26977 0 0.00 0 10607 36 0 0
sp_multiply_rows_for_qdistr 19657 19657 0 0.00 0 15087 203 0 0
sp_pay_from_customer 6866 6866 0 0.00 4 18863 135 0 0
sp_pay_to_supplier 5671 5671 0 0.00 5 17712 147 0 0
sp_payment_common 12537 12537 0 0.00 1 12429 22 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 13114 340 0 0
sp_qd_handle_on_invoice_upd_sts 10246 10246 0 0.00 0 32850 423 0 0
sp_qd_handle_on_reserve_upd_sts 9718 9718 0 0.00 0 15199 53 0 0
sp_reserve_write_off 9725 9725 0 0.00 7 16461 198 0 0
sp_supplier_invoice 5279 5279 0 0.00 7 80714 3836 0 0
sp_supplier_order 5277 5277 0 0.00 6 68925 1430 0 0
srv_aggregate_perf_data 109 109 0 0.00 2861 140717 13927 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 939 162397 44233 0 0
srv_find_qd_qs_mism 1019334 1019334 0 0.00 0 12623 5 0 0
srv_make_invnt_saldo 452 452 0 0.00 56 129807 4079 0 0
srv_make_money_saldo 691 691 0 0.00 1 32354 668 0 0
srv_recalc_idx_stat 5 5 0 0.00 208665 473183 357401 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4728 4728 0 0.00 0 21492 175 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 3710 99 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 8127 21 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1444 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 8059 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 566 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2180 30 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 20239 1529 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 1911 6 0 0
t$perf-norm:sp_client_order 19657 19657 0 0.00 0 5972 17 0 0
t$perf-norm:sp_customer_reserve 893 893 0 0.00 0 3426 9 0 0
t$perf-norm:sp_pay_from_customer 6866 6866 0 0.00 0 12914 12 0 0
t$perf-norm:sp_pay_to_supplier 5671 5671 0 0.00 0 8096 10 0 0
t$perf-norm:sp_reserve_write_off 9725 9725 0 0.00 0 3895 9 0 0
t$perf-norm:sp_supplier_invoice 5279 5279 0 0.00 0 1606 17 0 0
t$perf-norm:sp_supplier_order 5277 5277 0 0.00 0 17368 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 192 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 103 2 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 2470 25 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 1334 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 13 6 0 0
v_add_invoice_to_stock 9668 9422 246 2.54 0 9316 23 0 246
v_all_customers 35626 35626 0 0.00 0 19 1 0 0
v_all_suppliers 29558 29558 0 0.00 0 18 1 0 0
v_all_wares 39310 39310 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 9664 21 0 0
v_cancel_client_order 3460 3460 0 0.00 0 3066 7 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2651 11 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 3059 8 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 2121 8 0 0
v_cancel_supplier_invoice 824 802 22 2.66 0 1142 16 0 22
v_cancel_supplier_order 806 806 0 0.00 0 2641 11 0 0
v_cancel_write_off 1896 1896 0 0.00 0 9939 26 0 0
v_max_id_clo_ord 5277 5277 0 0.00 0 1101 2 0 0
v_max_id_clo_res 893 893 0 0.00 0 8172 31 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 6243 6 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 109 2 0 0
v_max_non_paid_realizn 3805 3805 0 0.00 0 5000 6 0 0
v_min_id_clo_ord 5277 5277 0 0.00 0 10430 10 0 0
v_min_id_clo_res 893 893 0 0.00 0 2807 7 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 8576 22 0 0
v_min_non_paid_invoice 5671 5671 0 0.00 0 6131 9 0 0
v_min_non_paid_realizn 6866 6866 0 0.00 0 6059 14 0 0
v_our_firm 9806 9806 0 0.00 0 15 1 0 0
v_reserve_write_off 19436 19436 0 0.00 0 7296 7 0 0
x_lock_depdocs_on_canc_invoice 11288 11288 0 0.00 0 11618 140 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 24396 529 0 0
x_make_qstorno_1000_1200 5277 5277 0 0.00 2 68914 1333 0 0
x_make_qstorno_1200_2000 5279 5279 0 0.00 4 80695 3655 0 0
x_make_qstorno_2100_3300 439174 439174 0 0.00 0 75505 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2021 13:28:54 to 24-02-2021 13:28:58.

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
10:57:00 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 25215 8192000000 9705903552 9997295616 777786448 8.671 100 86 172 0 806980128 7697616 21496064
10:59:34 32168 8192000000 9757555760 10059108352 826838880 9.167 100 84 168 0 859083360 11597680 20646800
11:01:12 14599 8192000000 9793600048 10108985344 859301456 9.493 100 73 146 2 895783472 20058976 16423040
11:02:30 17178 8192000000 9871769552 10200592384 917261120 10.069 100 80 160 0 976396800 41984672 17151008
11:03:50 6399 8192000000 9909612208 10255634432 954706112 10.437 100 77 154 1 1031330496 60791728 15832656
11:04:57 4032 8192000000 9962856480 10314653696 999623440 10.875 100 77 154 0 1077742000 63090816 15027744
11:06:04 2156 8192000000 10005035936 10364137472 1022625968 11.097 100 91 178 2 1115368736 75133200 17609568
11:07:08 2497 8192000000 10038680752 10405736448 1055685072 11.415 100 87 176 0 1149701744 76054512 17962160
11:08:12 6820 8192000000 10054032352 10444046336 1064690112 11.501 100 69 138 0 1159290880 80428800 14171968
11:09:19 9968 8192000000 9987276096 10369454080 1071149872 11.563 100 39 77 2 1107265904 27802160 8313872
11:10:30 6215 8192000000 10001052736 10391609344 1078293104 11.631 100 52 105 1 1109607152 20204496 11109552
11:11:38 6332 8192000000 10000594896 10395807744 1083540880 11.681 100 51 103 1 1113881456 18592160 11748416
11:12:45 43933 8192000000 9997940240 10404466688 1088976080 11.733 100 38 75 1 1121903184 24632592 8294512
11:14:30 3991 8192000000 10026324080 10429972480 1101047248 11.848 100 46 89 2 1145931776 35281440 9603088
11:15:37 7718 8192000000 10017319056 10410590208 1102669200 11.863 100 29 52 3 1122859200 14138384 6051616
11:16:46 7375 8192000000 10017365056 10411491328 1097183968 11.811 100 27 53 2 1126813376 23557904 6071504
11:17:58 46664 8192000000 9987565808 10384932864 1011249376 10.987 93 36 72 1 1033772944 14870784 7652784
11:19:54 4213 8192000000 9945933376 10327687168 1011612864 10.991 97 47 93 2 1046331808 24756752 9962192
11:21:02 9464 8192000000 9937227296 10332160000 1012195568 10.997 98 41 82 0 1044904976 22955296 9754112
11:22:14 69370 8192000000 9955475104 10356838400 1029318096 11.162 99 23 46 4 1064659376 30637376 4703904
11:24:24 4246 8192000000 9988247184 10373009408 1054847680 11.407 100 59 119 4 1092395488 24589152 12958656
11:25:29 7663 8192000000 9993827984 10390220800 1066836992 11.522 100 43 86 3 1093401024 17452384 9111648
11:26:39 7233 8192000000 9979700816 10377744384 1070695312 11.559 100 42 83 3 1088839920 8415600 9729008
11:27:52 114414 8192000000 10058644880 10465308672 1104027200 11.876 100 44 86 1 1173556032 60279568 9249264
11:30:51 12625 8192000000 10097566160 10502639616 1121562864 12.042 100 41 82 1 1207186912 76892864 8731184
11:32:06 35963 8192000000 10134541328 10564317184 1132620800 12.146 100 37 72 1 1248262080 107636448 8004832
11:33:50 11500 8192000000 10191253568 10609537024 1146509616 12.277 100 68 136 1 1306593520 145036448 15047456
11:35:05 2506 8192000000 10172556000 10599645184 1153001840 12.338 100 70 138 0 1281556512 113468272 15086400
11:36:16 6957 8192000000 10235617264 10653454336 1156048192 12.366 100 83 166 2 1346498448 172529440 17920816
11:37:29 10645 8192000000 10241909056 10655141888 1163863360 12.439 100 67 131 0 1347150448 169356736 13930352
11:38:50 3220 8192000000 10209128416 10618753024 1150333696 12.313 100 68 135 1 1312880224 148027392 14519136
11:39:57 8974 8192000000 10278041440 10691563520 1178930080 12.580 100 62 122 1 1377815104 185092976 13792048
11:41:11 3763 8192000000 10289454576 10710265856 1156470720 12.370 100 71 141 0 1395142384 222358176 16313488
11:42:19 1515 8192000000 10305821488 10706833408 1156247568 12.368 100 89 178 0 1404488384 228652672 19588144
11:43:23 2355 8192000000 10308089216 10708811776 1146584432 12.277 100 86 169 2 1409844464 242572528 20687504
11:44:35 4730 8192000000 10337982256 10740772864 1153903472 12.346 100 72 143 1 1443468560 273600864 15964224
11:45:47 6499 8192000000 10411076256 10813505536 1160131296 12.404 100 79 156 1 1508209440 330072736 18005408
11:46:57 1534 8192000000 10481433728 10883076096 1170399856 12.501 100 79 153 1 1579324768 389354000 19570912
11:48:04 1216 8192000000 10525709264 10925711360 1166917392 12.468 100 87 174 1 1622084480 434139456 21027632
11:49:11 1035 8192000000 10563232608 10964709376 1188183872 12.666 100 87 175 4 1654624432 444627792 21812768
11:50:16 918 8192000000 10628461216 11037667328 1194165712 12.722 100 77 152 1 1721727280 509070880 18490688
11:51:25 4907 8192000000 10601210304 11023769600 1200511424 12.781 100 58 117 0 1697466704 481879232 15076048
11:52:38 11264 8192000000 10633967520 11098423296 1194015632 12.721 100 41 82 0 1712802496 507896960 10889904
11:53:51 21187 8192000000 10551434864 11029164032 1156959088 12.375 100 35 70 0 1668248320 501152592 10136640
11:55:20 18389 8192000000 10502470496 11031261184 1131594912 12.136 99 28 56 0 1633863600 493284832 8983856
11:57:02 113206 8192000000 10074965024 10546016256 1137390560 12.191 100 6 12 1 1229061520 90407024 1263936
12:00:20 28910 8192000000 10090258560 10554388480 1135424864 12.172 99 36 71 1 1194623008 51637312 7560832
12:02:20 162396 8192000000 10085553136 10540531712 1110345616 11.936 98 17 34 0 1164408800 50802400 3260784
12:06:08 58540 8192000000 10060021920 10499862528 1128823680 12.110 99 31 61 1 1194229376 58706944 6698752
12:08:10 40593 8192000000 10068895616 10528710656 1113118656 11.962 97 36 67 1 1172937136 51717904 8100576
12:09:52 35464 8192000000 10080153088 10551492608 1139658240 12.212 100 24 48 1 1196800896 52380800 4761856
12:11:37 32308 8192000000 10111233888 10568495104 1138451360 12.201 99 15 30 3 1194757376 53283504 3022512
12:13:26 66502 8192000000 10166418096 10627342336 1132960832 12.149 98 23 45 0 1260858096 122253552 5643712
12:15:36 48571 8192000000 10165732880 10656362496 1109293072 11.926 97 18 36 0 1256845312 143400928 4151312
12:17:27 40126 8192000000 10069526240 10514698240 1116773280 11.996 98 16 32 1 1152082896 31954848 3354768
12:19:08 22119 8192000000 10124201280 10556104704 1153181168 12.339 100 54 108 0 1208988064 43671248 12135648
12:20:45 48227 8192000000 10107788512 10629971968 1114279344 11.973 97 11 22 2 1208500960 91757456 2464160
12:22:38 37040 8192000000 10064251360 10504998912 1098106816 11.820 97 28 56 0 1130025984 26315488 5603680
12:24:29 36372 8192000000 10110574848 10535919616 1067178240 11.525 95 43 86 0 1162472000 86589008 8704752
12:26:12 72617 8192000000 10070252560 10572181504 1103850272 11.874 99 13 26 1 1164320320 57882320 2587728
12:28:28 78556 8192000000 10044896320 10476843008 1088716208 11.730 97 35 70 3 1117364624 21331744 7316672
12:31:03 34214 8192000000 10060700128 10505650176 1105598352 11.891 100 7 14 0 1158312208 51205888 1507968
12:32:37 62350 8192000000 10102754560 10577993728 1122993696 12.055 100 12 23 0 1205598176 79941664 2662816
12:35:01 24238 8192000000 10053062000 10520244224 1105351392 11.888 98 15 32 1 1133997872 24837568 3808912
12:36:28 65199 8192000000 10080210896 10530324480 1097963360 11.818 97 13 26 0 1156703440 56382576 2357504
12:38:46 44757 8192000000 10062387344 10502094848 1072722640 11.578 96 19 37 1 1113746528 37153712 3870176
12:40:30 48752 8192000000 10078207056 10558070784 1126891472 12.092 100 33 64 2 1197225504 63172416 7161616
12:42:20 71145 8192000000 10138659584 10608009216 1145118032 12.264 100 27 54 0 1239316560 88305024 5893504
12:45:14 74368 8192000000 10077221488 10554044416 1104076480 11.876 97 11 22 0 1146510672 40121520 2312672
12:47:35 123706 8192000000 10087652368 10543476736 1133186912 12.151 99 22 44 1 1193811744 55801632 4823200
12:51:15 114747 8192000000 10117057968 10580197376 1123516752 12.060 98 11 22 0 1182561440 56497024 2547664
12:54:10 46833 8192000000 10085730960 10535755776 1117940576 12.008 98 14 28 0 1161958832 40334944 3683312
12:55:57 84194 8192000000 10075112144 10537500672 1125430224 12.078 98 16 31 1 1169617456 40534544 3652688
12:58:24 61505 8192000000 10110351424 10567512064 1137882144 12.196 99 27 54 0 1212409584 68834368 5693072
13:00:26 47269 8192000000 10186187488 10631946240 1112476048 11.956 96 34 68 0 1216095568 96120064 7499456
13:02:16 50515 8192000000 10135683296 10580721664 1106048288 11.895 97 13 26 0 1191248992 82556352 2644352
13:04:07 44436 8192000000 10104137712 10582933504 1112262896 11.954 97 17 34 1 1191240720 75543184 3434640
13:05:52 89070 8192000000 10106954848 10549559296 1148263856 12.293 100 34 68 0 1192912640 37018000 7630784
13:08:53 64037 8192000000 10094094816 10614128640 1153258304 12.340 100 3 6 0 1204221664 50615632 347728
13:10:57 58087 8192000000 10128789616 10564255744 1138329680 12.200 98 34 68 1 1186277200 40461840 7485680
13:13:06 95673 8192000000 10119840096 10576605184 1106592352 11.900 96 26 50 1 1171549280 59931136 5025792
13:15:42 59135 8192000000 10067759920 10508267520 1079781184 11.645 95 17 33 1 1113925488 30819360 3324944
13:17:51 11662 8192000000 10089831536 10528194560 1128869488 12.111 100 32 64 0 1176656224 41676304 6110432
13:19:04 19325 8192000000 10073805552 10516889600 1093423776 11.775 97 26 52 1 1130416368 31594592 5398000
13:20:24 162151 8192000000 10067470160 10521190400 1088611472 11.729 96 13 26 3 1136375728 45260512 2503744
13:24:33 31241 8192000000 10027630064 10494242816 1104978608 11.885 99 3 6 0 1137374640 31891184 504848
13:26:05 34534 8192000000 10035915632 10499739648 1106653200 11.901 99 3 6 0 1130613152 23412320 547632

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-02-2021 13:29:00 to 24-02-2021 13:29:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-02-2021 13:29:16 to 24-02-2021 13:29:18.

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          232869
MON$OLDEST_ACTIVE               233555
MON$OLDEST_SNAPSHOT             233555
MON$NEXT_TRANSACTION            233555
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                       2116544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 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.2372 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 ---
:192: 
:193: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:03 2021
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:195: 
:196: 
:197: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:04 2021
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:199: 
:200: 
:201: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:203: 
:204: 
:205: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:211: 
:212: 
:213: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:215: 
:216: 
:217: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:227: 
:228: 
:229: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:231: 
:232: 
:233: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:235: 
:236: 
:237: mscs-test-161.budzdorov.ru    Wed Feb 24 13:27:12 2021
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:239: 
--- end of diff output ---

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

24.02.21 13:29:19. 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-02-24 13:29:22 No severe PSQL-related problems occured
24.02.21 13:29:23. End of report.