Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.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 16480236 kB
MemAvailable 31227696 kB
Buffers 0 kB
Cached 15226516 kB
SwapCached 4872 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-09 13:26:57
DTS_END                         2021-02-09 13:26:57.8420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57304
TRN_ID                          228294
ATT_ID                          575
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: 8877.19 25979 1065263
customer order: creation 163.13 429 19575
customer order: refuse 14.15 345 1698
order to supplier: creation 42.20 1374 5064
order to supplier: removal 3.13 123533 376
invoice (draft): creation 44.18 3804 5302
invoice (draft): removal 51.34 967 6161
invoice accept: apply 39.36 18945 4723
invoice accept: cancel 45.98 960 5518
customer reserve: creation 3689.55 181 442746
customer reserve: removal 4507.93 68 540951
realization accept: apply 81.03 189 9723
realization accept: cancel 73.79 113 8855
payment from customer: creation 55.72 111 6686
payment from customer: removal 5.32 121 638
payment to supplier: creation 46.93 130 5631
payment to supplier: removal 3.26 99 391
service: total inventory turnovers 3.49 3217 419
service: total monetary turnovers 5.83 591 700
service: aggregate perf. data 0.85 16551 102
service: refresh index statistics 0.03 347856 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-02-2021 13:28:37 to 09-02-2021 13:28:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2128
TEST_TIME 2 3999
TEST_TIME 3 5497
TEST_TIME 4 4833
TEST_TIME 5 4533
TEST_TIME 6 4786
TEST_TIME 7 5095
TEST_TIME 8 5263
TEST_TIME 9 5464
TEST_TIME 10 6208
TEST_TIME 11 6542
TEST_TIME 12 6872
TEST_TIME 13 7277
TEST_TIME 14 7312
TEST_TIME 15 7539
TEST_TIME 16 7712
TEST_TIME 17 7823
TEST_TIME 18 8009
TEST_TIME 19 8203
TEST_TIME 20 8257
TEST_TIME 21 9125
TEST_TIME 22 8989
TEST_TIME 23 9460
TEST_TIME 24 9456
TEST_TIME 25 9657
TEST_TIME 26 9474
TEST_TIME 27 9619
TEST_TIME 28 10187
TEST_TIME 29 10716
TEST_TIME 30 11186
TEST_TIME 31 11178
TEST_TIME 32 11113
TEST_TIME 33 10813
TEST_TIME 34 10634
TEST_TIME 35 10460
TEST_TIME 36 10419
TEST_TIME 37 10241
TEST_TIME 38 10081
TEST_TIME 39 9933
TEST_TIME 40 9934
TEST_TIME 41 9786
TEST_TIME 42 9770
TEST_TIME 43 9643
TEST_TIME 44 9528
TEST_TIME 45 9411
TEST_TIME 46 9432
TEST_TIME 47 9373
TEST_TIME 48 9347
TEST_TIME 49 9263
TEST_TIME 50 9242
TEST_TIME 51 9272
TEST_TIME 52 9372
TEST_TIME 53 9350
TEST_TIME 54 9405
TEST_TIME 55 9388
TEST_TIME 56 9374
TEST_TIME 57 9368
TEST_TIME 58 9357
TEST_TIME 59 9494
TEST_TIME 60 9423
TEST_TIME 61 9294
TEST_TIME 62 9227
TEST_TIME 63 9134
TEST_TIME 64 9087
TEST_TIME 65 9048
TEST_TIME 66 8984
TEST_TIME 67 9189
TEST_TIME 68 9158
TEST_TIME 69 9120
TEST_TIME 70 9148
TEST_TIME 71 9087
TEST_TIME 72 9200
TEST_TIME 73 9156
TEST_TIME 74 9239
TEST_TIME 75 9262
TEST_TIME 76 9255
TEST_TIME 77 9249
TEST_TIME 78 9301
TEST_TIME 79 9311
TEST_TIME 80 9217
TEST_TIME 81 9193
TEST_TIME 82 9157
TEST_TIME 83 9120
TEST_TIME 84 9041
TEST_TIME 85 9042
TEST_TIME 86 8990
TEST_TIME 87 9005
TEST_TIME 88 8984
TEST_TIME 89 8977
TEST_TIME 90 8936
TEST_TIME 91 8925
TEST_TIME 92 9016
TEST_TIME 93 9058
TEST_TIME 94 9017
TEST_TIME 95 9070
TEST_TIME 96 9121
TEST_TIME 97 9149
TEST_TIME 98 9135
TEST_TIME 99 9053
TEST_TIME 100 9049
TEST_TIME 101 9008
TEST_TIME 102 8954
TEST_TIME 103 8931
TEST_TIME 104 8896
TEST_TIME 105 8916
TEST_TIME 106 8921
TEST_TIME 107 8939
TEST_TIME 108 8928
TEST_TIME 109 8905
TEST_TIME 110 8914
TEST_TIME 111 8979
TEST_TIME 112 8953
TEST_TIME 113 9038
TEST_TIME 114 9054
TEST_TIME 115 9054
TEST_TIME 116 8982
TEST_TIME 117 8920
TEST_TIME 118 8911
TEST_TIME 119 8884
TEST_TIME 120 8878

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2021 13:28:41 to 09-02-2021 13:28:44.

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 1474801 1474801 0 0.00 0 20360 7 0 0
doc_list_biud 1474800 1474800 0 0.00 0 82723 32 0 0
sp_add_invoice_to_stock 4865 4723 142 2.91 49 157848 18946 0 142
sp_cancel_adding_invoice 5518 5518 0 0.00 6 100311 960 0 0
sp_cancel_client_order 1698 1698 0 0.00 5 12382 345 0 0
sp_cancel_customer_reserve 540951 540951 0 0.00 1 27790 68 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 7611 122 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 8075 99 0 0
sp_cancel_supplier_invoice 6173 6161 12 0.19 2 82729 968 0 12
sp_cancel_supplier_order 376 376 0 0.00 6 1018659 123533 0 0
sp_cancel_write_off 8855 8855 0 0.00 1 30128 114 0 0
sp_client_order 19575 19575 0 0.00 4 18477 430 0 0
sp_customer_reserve 442746 442746 0 0.00 0 54530 182 0 0
sp_fill_shopping_cart 29931 29931 0 0.00 1 15992 53 0 0
sp_fill_shopping_cart_clo_res 442745 442745 0 0.00 0 27720 17 0 0
sp_get_clo_for_invoice 4721 4721 0 0.00 0 41774 452 0 0
sp_kill_cost_storno 15402 15402 0 0.00 0 11743 59 0 0
sp_kill_qstorno_ret_qs2qd 1097294 1097294 0 0.00 0 82722 37 0 0
sp_kill_qty_storno 578003 578003 0 0.00 0 82723 80 0 0
sp_lock_selected_doc 19893 19893 0 0.00 0 8593 7 0 0
sp_make_cost_storno 26761 26761 0 0.00 0 14081 57 0 0
sp_multiply_rows_for_pdistr 26761 26761 0 0.00 0 16161 34 0 0
sp_multiply_rows_for_qdistr 19575 19575 0 0.00 0 18467 237 0 0
sp_pay_from_customer 6686 6686 0 0.00 4 20372 112 0 0
sp_pay_to_supplier 5631 5631 0 0.00 5 16173 130 0 0
sp_payment_common 12317 12317 0 0.00 1 7746 19 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 12375 312 0 0
sp_qd_handle_on_invoice_upd_sts 10239 10239 0 0.00 0 18245 416 0 0
sp_qd_handle_on_reserve_upd_sts 9723 9723 0 0.00 0 11969 51 0 0
sp_reserve_write_off 9723 9723 0 0.00 7 24173 189 0 0
sp_supplier_invoice 5302 5302 0 0.00 5 98161 3804 0 0
sp_supplier_order 5064 5064 0 0.00 5 60105 1374 0 0
srv_aggregate_perf_data 102 102 0 0.00 2920 296185 16552 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1090 201822 43957 0 0
srv_find_qd_qs_mism 1019734 1019734 0 0.00 0 18930 6 0 0
srv_make_invnt_saldo 419 419 0 0.00 60 85006 3218 0 0
srv_make_money_saldo 700 700 0 0.00 1 11381 592 0 0
srv_recalc_idx_stat 4 4 0 0.00 118928 607494 347857 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4723 4723 0 0.00 0 7839 176 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 5460 88 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 3292 15 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 1423 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 627 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 499 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1019 21 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 15645 1583 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 5062 8 0 0
t$perf-norm:sp_client_order 19575 19575 0 0.00 0 5497 18 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 1245 7 0 0
t$perf-norm:sp_pay_from_customer 6686 6686 0 0.00 0 3832 9 0 0
t$perf-norm:sp_pay_to_supplier 5631 5631 0 0.00 0 2812 7 0 0
t$perf-norm:sp_reserve_write_off 9723 9723 0 0.00 0 5025 8 0 0
t$perf-norm:sp_supplier_invoice 5302 5302 0 0.00 0 9577 20 0 0
t$perf-norm:sp_supplier_order 5064 5064 0 0.00 0 1686 13 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 254 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 43 2 0 0
t$perf-norm:srv_make_invnt_saldo 419 419 0 0.00 0 865 11 0 0
t$perf-norm:srv_make_money_saldo 700 700 0 0.00 0 709 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9726 9442 284 2.92 0 8077 18 0 284
v_all_customers 35448 35448 0 0.00 0 45 1 0 0
v_all_suppliers 28756 28756 0 0.00 0 25 1 0 0
v_all_wares 39148 39148 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 5428 14 0 0
v_cancel_client_order 3396 3396 0 0.00 0 2819 6 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 2518 7 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 2559 8 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 411 5 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 3022 19 0 24
v_cancel_supplier_order 752 752 0 0.00 0 1700 7 0 0
v_cancel_write_off 1846 1846 0 0.00 0 4810 16 0 0
v_max_id_clo_ord 5064 5064 0 0.00 0 2820 2 0 0
v_max_id_clo_res 959 959 0 0.00 0 1095 9 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 5327 9 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 513 3 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 3097 4 0 0
v_min_id_clo_ord 5064 5064 0 0.00 0 6026 9 0 0
v_min_id_clo_res 959 959 0 0.00 0 2104 8 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 7991 8 0 0
v_min_non_paid_invoice 5631 5631 0 0.00 0 6496 11 0 0
v_min_non_paid_realizn 6686 6686 0 0.00 0 4655 11 0 0
v_our_firm 9780 9780 0 0.00 0 15 1 0 0
v_reserve_write_off 19446 19446 0 0.00 0 8150 8 0 0
x_lock_depdocs_on_canc_invoice 11287 11287 0 0.00 0 31536 113 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 3173 296 0 0
x_make_qstorno_1000_1200 5064 5064 0 0.00 2 60095 1290 0 0
x_make_qstorno_1200_2000 5302 5302 0 0.00 2 98011 3592 0 0
x_make_qstorno_2100_3300 442746 442746 0 0.00 0 54529 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-02-2021 13:28:45 to 09-02-2021 13:28:49.

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:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 21651 8192000000 9716583072 10010288128 800559392 8.902 100 86 172 0 829003008 7494848 20948768
10:59:28 10510 8192000000 9754186576 10056699904 832485168 9.224 100 88 176 0 865034816 11620544 20929104
11:00:43 13125 8192000000 9781084544 10091565056 852524640 9.425 100 84 168 0 888752704 16480016 19748048
11:02:08 8302 8192000000 9854963184 10183745536 903195680 9.930 100 82 163 0 964301152 43083952 18021520
11:03:23 8900 8192000000 9909535056 10250489856 955868608 10.449 100 82 165 0 1018374112 44770144 17735360
11:04:36 5845 8192000000 9960364032 10315685888 995133376 10.831 100 77 152 0 1071949776 60808992 16007408
11:05:44 3497 8192000000 10007150512 10368495616 1026513936 11.135 100 89 180 0 1114266480 70078864 17673680
11:06:48 1811 8192000000 10020941120 10382577664 1045723744 11.320 100 89 174 1 1130396752 67114496 17558512
11:07:51 3722 8192000000 10029899504 10410233856 1060811456 11.464 100 59 118 0 1158143696 85966176 11366064
11:08:55 36261 8192000000 10000559088 10377601024 1068435888 11.537 100 65 134 1 1100031680 17435872 14159920
11:10:32 5126 8192000000 9995568832 10386837504 1077196352 11.621 100 48 96 2 1106225344 18717632 10311360
11:11:39 5299 8192000000 10000664688 10396917760 1086276864 11.707 100 35 70 0 1112046400 18057488 7712048
11:12:45 12101 8192000000 10000725344 10407993344 1085634112 11.701 100 23 46 1 1106748000 16090256 5023632
11:13:58 51698 8192000000 10018154672 10405056512 1092492832 11.766 100 69 137 2 1119056912 10887120 15676960
11:15:51 5623 8192000000 10010855136 10417143808 1085525952 11.700 99 39 78 1 1130437280 36033888 8877440
11:16:56 4266 8192000000 10017389488 10414080000 1085396624 11.699 99 54 107 3 1113201152 15460352 12344176
11:18:01 7568 8192000000 9981769904 10376314880 1023382240 11.105 96 40 79 1 1051771200 18908304 9480656
11:19:11 67048 8192000000 9928808416 10325929984 955907840 10.449 91 27 54 0 981994336 19785120 6301376
11:21:18 4862 8192000000 9951591424 10331086848 1009811376 10.974 98 41 84 1 1056285696 38022272 8452048
11:22:27 6451 8192000000 9949900672 10342391808 1032325584 11.191 100 20 41 0 1069324880 32890032 4109264
11:23:33 8175 8192000000 9952246400 10337624064 1045948464 11.322 100 19 37 0 1072598800 22524368 4125968
11:24:42 57493 8192000000 9964448352 10367983616 1058510480 11.442 100 14 28 0 1076547648 14953632 3083536
11:26:42 8473 8192000000 9982035808 10383986688 1075090416 11.601 100 37 74 0 1100761712 17702672 7968624
11:28:00 10908 8192000000 10075124720 10467352576 1102655712 11.863 100 74 148 2 1182105136 64455248 14994176
11:29:16 7626 8192000000 10093113680 10489597952 1119730464 12.024 100 61 122 2 1198855824 66815968 12309392
11:30:26 45962 8192000000 10084904336 10505289728 1126805856 12.091 100 26 52 1 1178552400 46079312 5667232
11:32:15 13904 8192000000 10144874320 10552713216 1140201952 12.217 100 51 103 0 1245109584 93820576 11087056
11:33:33 2085 8192000000 10178882528 10582568960 1150490144 12.314 100 74 141 1 1280309648 114628528 15190976
11:34:39 7575 8192000000 10214038384 10619535360 1153251472 12.340 99 70 140 2 1313704736 145269152 15184112
11:35:55 7927 8192000000 10246302880 10656747520 1163038240 12.432 100 65 126 1 1352477984 175478352 13961392
11:37:09 2781 8192000000 10256762912 10660966400 1162306560 12.425 99 72 143 0 1350593504 172864768 15422176
11:38:16 6667 8192000000 10230052416 10639142912 1136440720 12.182 98 63 122 0 1326500032 176404400 13654912
11:39:30 1089 8192000000 10267996336 10666172416 1160200304 12.405 100 93 181 1 1367384576 187120960 20063312
11:40:36 2524 8192000000 10266805216 10675167232 1153484368 12.342 100 82 163 0 1369674432 197144832 19045232
11:41:49 6558 8192000000 10264276192 10683420672 1155813328 12.364 100 72 144 0 1359868720 187809440 16245952
11:43:02 2655 8192000000 10306613168 10704900096 1161932784 12.421 100 86 169 0 1394731584 213244784 19554016
11:44:08 1795 8192000000 10359284128 10764443648 1160476192 12.408 99 85 167 0 1449934464 270208896 19249376
11:45:15 3109 8192000000 10381351120 10787602432 1155244992 12.359 100 77 154 2 1480099920 306984352 17870576
11:46:25 2045 8192000000 10417927872 10821750784 1152355680 12.332 99 82 161 1 1519039872 347648064 19036128
11:47:34 7002 8192000000 10355133024 10804379648 1164797856 12.448 100 40 80 0 1451344160 276247440 10298864
11:48:50 6951 8192000000 10375922576 10809438208 1155516096 12.361 100 55 110 0 1485734160 316978976 13239088
11:50:08 16048 8192000000 10362082704 10791837696 1159958496 12.403 100 36 71 0 1472107696 303039984 9109216
11:51:29 14742 8192000000 10359216544 10831290368 1153740272 12.345 100 29 58 1 1467775584 306641472 7393840
11:52:54 13550 8192000000 10397937664 10842701824 1130983888 12.131 100 25 50 1 1491181504 353015072 7182544
11:54:16 33229 8192000000 10322468720 10779373568 1139287776 12.209 100 21 42 1 1462778896 317730272 5760848
11:55:51 25602 8192000000 10122332528 10623148032 1099279008 11.831 97 14 28 2 1252066640 149415328 3372304
11:57:17 80625 8192000000 10066483440 10580045824 1111145840 11.943 99 11 22 1 1217525792 103930288 2449664
12:00:20 46403 8192000000 10070639152 10511347712 1115032464 11.980 98 17 34 1 1158868608 40795904 3040240
12:02:08 73838 8192000000 10063625856 10540240896 1114979648 11.980 98 9 18 1 1166337728 49564496 1793584
12:04:22 53986 8192000000 10106222160 10556182528 1145227776 12.265 100 15 30 0 1189581296 41257232 3096288
12:06:18 69248 8192000000 10097261344 10540617728 1120735728 12.034 98 20 41 1 1204084512 78870432 4478352
12:08:45 68060 8192000000 10096446464 10545143808 1142964688 12.243 100 20 38 0 1196701536 48951936 4784912
12:10:57 53551 8192000000 10087537200 10545963008 1129058096 12.112 99 9 18 0 1188845296 57540496 2246704
12:12:55 57799 8192000000 10089511184 10581667840 1145484224 12.267 100 5 10 0 1192163280 45683904 995152
12:15:01 30300 8192000000 10124292144 10576203776 1156865344 12.374 100 46 91 4 1205677024 39137632 9674048
12:16:50 53231 8192000000 10129848672 10617606144 1141877040 12.233 99 13 26 1 1235316608 90544112 2895456
12:18:45 66896 8192000000 10093328384 10540036096 1123454432 12.060 98 14 28 0 1178628736 52246256 2928048
12:22:00 52376 8192000000 10074819248 10509959168 1131126288 12.132 99 29 55 1 1180966432 43983728 5856416
12:23:58 19890 8192000000 10112169840 10534031360 1136697584 12.184 99 63 127 0 1190286144 40346848 13241712
12:25:18 100281 8192000000 10065218288 10561781760 1107435280 11.908 97 17 35 0 1182871088 71856976 3578832
12:28:00 49234 8192000000 10069420224 10524545024 1121196272 12.038 99 32 64 1 1159566336 31558544 6811520
12:30:00 69575 8192000000 10106129936 10586853376 1137244720 12.190 99 8 16 0 1197077376 58172240 1660416
12:32:13 63279 8192000000 10171477600 10619904000 1138030560 12.197 99 18 36 0 1247819056 105981824 3806672
12:34:17 86195 8192000000 10140939776 10590482432 1104251248 11.878 96 13 26 0 1159452016 52571648 2629120
12:36:48 73599 8192000000 10143889568 10585059328 1075897312 11.608 94 15 30 0 1173452528 94066064 3489152
12:39:02 47347 8192000000 10107948016 10533552128 1117087456 11.999 100 37 72 1 1211754848 86763056 7904336
12:40:56 79678 8192000000 10057603072 10516434944 1075496064 11.605 96 17 33 3 1122654512 44248256 2910192
12:43:16 49157 8192000000 10097031536 10563932160 1115830192 11.988 99 31 62 2 1181002704 58791424 6381088
12:45:06 201822 8192000000 10081163728 10567483392 1092301760 11.765 96 16 32 2 1186953728 90970288 3681680
12:50:23 28509 8192000000 10080489344 10557837312 1114970336 11.979 99 7 14 0 1178239184 61783984 1484864
12:51:54 59394 8192000000 10050578768 10525765632 1083116288 11.677 97 4 8 0 1125084352 41200192 767872
12:53:57 58248 8192000000 10071277392 10526777344 1093762752 11.778 97 14 28 0 1141589152 44883248 2943152
12:55:56 40320 8192000000 10113489136 10547789824 1128971744 12.112 100 21 42 0 1215021008 81432928 4616336
12:57:42 46040 8192000000 10161684928 10602848256 1131386560 12.134 99 25 50 1 1245587712 108313472 5887680
12:59:28 51720 8192000000 10119299568 10556764160 1139651136 12.212 100 14 28 1 1204831472 62059216 3121120
13:01:20 75407 8192000000 10111986912 10621464576 1121491040 12.041 98 7 14 0 1216232576 93330784 1410752
13:04:30 130338 8192000000 10108591552 10574258176 1130612432 12.127 99 40 80 1 1206422752 67584368 8225952
13:08:00 54765 8192000000 10082712352 10540642304 1143624000 12.250 100 20 39 1 1178186496 30303728 4258768
13:10:12 63182 8192000000 10127081616 10587693056 1143267520 12.246 99 24 47 2 1211071648 62244288 5559840
13:12:22 64631 8192000000 10130969792 10607693824 1126313488 12.087 97 18 36 0 1214035648 83009888 4712272
13:14:30 55661 8192000000 10122609200 10574987264 1147682320 12.288 100 25 50 1 1198551504 45555424 5313760
13:16:28 65114 8192000000 10117700112 10616188928 1108469632 11.918 96 22 44 1 1193151888 79667680 5014576
13:18:44 22578 8192000000 10136444592 10565476352 1119102704 12.019 97 39 78 0 1205055824 77935296 8017824
13:20:10 55033 8192000000 10096690320 10556252160 1095692352 11.797 96 28 56 1 1152033056 50825696 5515008
13:22:13 65963 8192000000 10078852896 10565345280 1124516768 12.070 100 10 20 0 1181004976 54774000 1714208
13:24:38 52589 8192000000 10064431360 10540871680 1121871152 12.045 99 5 10 0 1155921152 33242176 807824
13:26:32 34730 8192000000 9107156496 9398005760 240302560 2.849 22 3 6 0 268838032 28017296 518176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-02-2021 13:28:51 to 09-02-2021 13:29:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          228959
MON$OLDEST_ACTIVE               228960
MON$OLDEST_SNAPSHOT             228960
MON$NEXT_TRANSACTION            228960
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                       2110336
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:160: 
:161: mscs-test-161.budzdorov.ru    Tue Feb  9 13:27:16 2021
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Tue Feb  9 13:27:16 2021
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Tue Feb  9 13:27:16 2021
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Tue Feb  9 13:27:16 2021
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:175: 
--- end of diff output ---

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

09.02.21 13:29:10. 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-09 13:29:12 No severe PSQL-related problems occured
09.02.21 13:29:14. End of report.