Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 07:39
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16539480 kB
MemAvailable 31395508 kB
Buffers 0 kB
Cached 15182884 kB
SwapCached 1796 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.1
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-12-31 07:37:09
DTS_END                         2021-12-31 07:37:09.8270
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/41110
TRN_ID                          227521
ATT_ID                          603
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: 8899.90 19632 1067988
customer order: creation 167.88 427 20145
customer order: refuse 14.32 383 1718
order to supplier: creation 43.61 1515 5233
order to supplier: removal 3.27 142540 392
invoice (draft): creation 44.13 3411 5295
invoice (draft): removal 52.68 1113 6321
invoice accept: apply 39.56 20743 4747
invoice accept: cancel 47.38 975 5685
customer reserve: creation 3607.22 206 432866
customer reserve: removal 4584.57 80 550148
realization accept: apply 80.78 219 9694
realization accept: cancel 90.05 137 10806
payment from customer: creation 56.37 139 6764
payment from customer: removal 5.83 118 700
payment to supplier: creation 47.65 139 5718
payment to supplier: removal 3.54 82 425
service: total inventory turnovers 4.03 3454 483
service: total monetary turnovers 6.20 643 744
service: aggregate perf. data 0.83 20115 99
service: refresh index statistics 0.04 196204 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-12-2021 07:39:50 to 31-12-2021 07:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7262
TEST_TIME 2 6710
TEST_TIME 3 6942
TEST_TIME 4 5873
TEST_TIME 5 5612
TEST_TIME 6 5254
TEST_TIME 7 5559
TEST_TIME 8 5728
TEST_TIME 9 5800
TEST_TIME 10 5879
TEST_TIME 11 6189
TEST_TIME 12 6538
TEST_TIME 13 6700
TEST_TIME 14 7005
TEST_TIME 15 6894
TEST_TIME 16 6968
TEST_TIME 17 6910
TEST_TIME 18 6901
TEST_TIME 19 7361
TEST_TIME 20 7245
TEST_TIME 21 7367
TEST_TIME 22 8603
TEST_TIME 23 8860
TEST_TIME 24 9157
TEST_TIME 25 9179
TEST_TIME 26 9592
TEST_TIME 27 10831
TEST_TIME 28 11510
TEST_TIME 29 11431
TEST_TIME 30 11061
TEST_TIME 31 10832
TEST_TIME 32 10582
TEST_TIME 33 10542
TEST_TIME 34 10432
TEST_TIME 35 10375
TEST_TIME 36 10243
TEST_TIME 37 10074
TEST_TIME 38 10009
TEST_TIME 39 9890
TEST_TIME 40 9838
TEST_TIME 41 9712
TEST_TIME 42 9572
TEST_TIME 43 9712
TEST_TIME 44 9616
TEST_TIME 45 9621
TEST_TIME 46 9561
TEST_TIME 47 9630
TEST_TIME 48 9587
TEST_TIME 49 9737
TEST_TIME 50 9703
TEST_TIME 51 9705
TEST_TIME 52 9762
TEST_TIME 53 9693
TEST_TIME 54 9656
TEST_TIME 55 9711
TEST_TIME 56 9584
TEST_TIME 57 9525
TEST_TIME 58 9444
TEST_TIME 59 9416
TEST_TIME 60 9317
TEST_TIME 61 9232
TEST_TIME 62 9170
TEST_TIME 63 9189
TEST_TIME 64 9140
TEST_TIME 65 9277
TEST_TIME 66 9275
TEST_TIME 67 9266
TEST_TIME 68 9271
TEST_TIME 69 9355
TEST_TIME 70 9343
TEST_TIME 71 9321
TEST_TIME 72 9346
TEST_TIME 73 9369
TEST_TIME 74 9401
TEST_TIME 75 9408
TEST_TIME 76 9469
TEST_TIME 77 9399
TEST_TIME 78 9317
TEST_TIME 79 9266
TEST_TIME 80 9219
TEST_TIME 81 9158
TEST_TIME 82 9148
TEST_TIME 83 9093
TEST_TIME 84 9119
TEST_TIME 85 9065
TEST_TIME 86 9066
TEST_TIME 87 9074
TEST_TIME 88 9111
TEST_TIME 89 9119
TEST_TIME 90 9128
TEST_TIME 91 9152
TEST_TIME 92 9138
TEST_TIME 93 9171
TEST_TIME 94 9171
TEST_TIME 95 9194
TEST_TIME 96 9238
TEST_TIME 97 9186
TEST_TIME 98 9134
TEST_TIME 99 9080
TEST_TIME 100 9069
TEST_TIME 101 9028
TEST_TIME 102 8973
TEST_TIME 103 8922
TEST_TIME 104 8912
TEST_TIME 105 8894
TEST_TIME 106 8881
TEST_TIME 107 8865
TEST_TIME 108 8839
TEST_TIME 109 8846
TEST_TIME 110 8864
TEST_TIME 111 8863
TEST_TIME 112 8964
TEST_TIME 113 8951
TEST_TIME 114 8999
TEST_TIME 115 9081
TEST_TIME 116 9036
TEST_TIME 117 9034
TEST_TIME 118 8994
TEST_TIME 119 8970
TEST_TIME 120 8903

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-12-2021 07:39:54 to 31-12-2021 07:39:58.

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 1469423 1469423 0 0.00 0 18654 8 0 0
doc_list_biud 1469419 1469419 0 0.00 0 68328 38 0 0
sp_add_invoice_to_stock 4877 4747 130 2.66 28 212960 20743 0 130
sp_cancel_adding_invoice 5685 5685 0 0.00 6 91858 975 0 0
sp_cancel_client_order 1718 1718 0 0.00 4 9324 384 0 0
sp_cancel_customer_reserve 550148 550148 0 0.00 1 48721 80 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 8077 119 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 4694 82 0 0
sp_cancel_supplier_invoice 6329 6321 8 0.12 2 68334 1113 0 8
sp_cancel_supplier_order 392 392 0 0.00 6 1284418 142541 0 0
sp_cancel_write_off 10806 10806 0 0.00 1 17654 138 0 0
sp_client_order 20145 20145 0 0.00 4 35196 427 0 0
sp_customer_reserve 432866 432866 0 0.00 0 124343 207 0 0
sp_fill_shopping_cart 30671 30671 0 0.00 1 17035 47 0 0
sp_fill_shopping_cart_clo_res 432858 432858 0 0.00 0 27638 18 0 0
sp_get_clo_for_invoice 4737 4737 0 0.00 0 38662 413 0 0
sp_kill_cost_storno 17616 17616 0 0.00 0 8072 65 0 0
sp_kill_qstorno_ret_qs2qd 1117815 1117815 0 0.00 0 68326 44 0 0
sp_kill_qty_storno 589500 589500 0 0.00 0 68328 92 0 0
sp_lock_selected_doc 20142 20142 0 0.00 0 7934 8 0 0
sp_make_cost_storno 26913 26913 0 0.00 0 14758 71 0 0
sp_multiply_rows_for_pdistr 26912 26912 0 0.00 0 9802 34 0 0
sp_multiply_rows_for_qdistr 20145 20145 0 0.00 0 35175 235 0 0
sp_pay_from_customer 6764 6764 0 0.00 4 22499 140 0 0
sp_pay_to_supplier 5718 5718 0 0.00 4 14128 139 0 0
sp_payment_common 12482 12482 0 0.00 1 12578 26 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 9316 345 0 0
sp_qd_handle_on_invoice_upd_sts 10422 10422 0 0.00 0 32303 389 0 0
sp_qd_handle_on_reserve_upd_sts 9693 9693 0 0.00 0 33394 65 0 0
sp_reserve_write_off 9694 9694 0 0.00 6 33469 220 0 0
sp_supplier_invoice 5295 5295 0 0.00 7 80302 3411 0 0
sp_supplier_order 5233 5233 0 0.00 5 40378 1515 0 0
srv_aggregate_perf_data 99 99 0 0.00 2409 383447 20115 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1163 124476 46130 0 0
srv_find_qd_qs_mism 1022661 1022661 0 0.00 0 18653 6 0 0
srv_make_invnt_saldo 483 483 0 0.00 43 114649 3455 0 0
srv_make_money_saldo 744 744 0 0.00 1 13684 643 0 0
srv_recalc_idx_stat 5 5 0 0.00 96803 302893 196205 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4747 4747 0 0.00 0 9365 174 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 6127 112 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 7730 21 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1901 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 9491 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 8858 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1124 12 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 39915 1696 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 3685 10 0 0
t$perf-norm:sp_client_order 20145 20145 0 0.00 0 15256 20 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 4549 17 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 5548 12 0 0
t$perf-norm:sp_pay_to_supplier 5718 5718 0 0.00 0 5299 11 0 0
t$perf-norm:sp_reserve_write_off 9694 9694 0 0.00 0 12320 17 0 0
t$perf-norm:sp_supplier_invoice 5295 5295 0 0.00 0 8678 21 0 0
t$perf-norm:sp_supplier_order 5233 5233 0 0.00 0 14559 24 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 467 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 51 2 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 881 9 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 11204 20 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 9734 9474 260 2.67 0 3449 18 0 260
v_all_customers 36378 36378 0 0.00 0 36 1 0 0
v_all_suppliers 29538 29538 0 0.00 0 56 1 0 0
v_all_wares 40290 40290 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2106 11 0 0
v_cancel_client_order 3436 3436 0 0.00 0 3148 7 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 7369 14 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2436 9 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 500 4 0 0
v_cancel_supplier_invoice 864 848 16 1.85 0 6090 23 0 16
v_cancel_supplier_order 784 784 0 0.00 0 1949 8 0 0
v_cancel_write_off 1930 1930 0 0.00 0 17584 34 0 0
v_max_id_clo_ord 5233 5233 0 0.00 0 310 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 3150 21 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 3220 4 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 959 3 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 1210 3 0 0
v_min_id_clo_ord 5233 5233 0 0.00 0 5175 5 0 0
v_min_id_clo_res 944 944 0 0.00 0 4275 9 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 5242 7 0 0
v_min_non_paid_invoice 5718 5718 0 0.00 0 6219 14 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 9914 20 0 0
v_our_firm 10118 10118 0 0.00 0 24 1 0 0
v_reserve_write_off 19384 19384 0 0.00 0 11619 8 0 0
x_lock_depdocs_on_canc_invoice 11582 11582 0 0.00 0 19310 132 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 8710 365 0 0
x_make_qstorno_1000_1200 5233 5233 0 0.00 2 40368 1438 0 0
x_make_qstorno_1200_2000 5295 5295 0 0.00 4 78077 3225 0 0
x_make_qstorno_2100_3300 432866 432866 0 0.00 0 124341 186 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 31-12-2021 07:39:58 to 31-12-2021 07:40:03.

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
05:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 22712 8192000000 9730135488 10026188800 815255152 9.051 100 94 188 0 845581600 7760336 22566112
05:09:44 17690 8192000000 9762418592 10068627456 837678832 9.276 100 92 184 0 872016640 12696304 21641504
05:11:08 15559 8192000000 9806423168 10122096640 873776448 9.638 100 91 182 2 914395920 19217008 21402464
05:12:30 3775 8192000000 9856344144 10188312576 909798288 9.995 100 81 162 0 968038320 40070240 18169792
05:13:38 9577 8192000000 9916597120 10257248256 951543200 10.406 100 92 183 1 1022870256 51741168 19585888
05:14:51 6529 8192000000 9958481856 10310483968 982340816 10.707 100 81 162 1 1079615904 81197872 16077216
05:16:00 3769 8192000000 9979141984 10335047680 1009164144 10.967 100 89 176 0 1090349296 63955760 17229392
05:17:06 2777 8192000000 10015130576 10380120064 1032385632 11.191 100 87 173 0 1127648480 78319728 16943120
05:18:10 2259 8192000000 10012433248 10385399808 1059674416 11.453 100 89 178 1 1129681792 50937280 19070096
05:19:15 20105 8192000000 10020805456 10403975168 1071464560 11.566 100 61 122 1 1141262256 57157712 12639984
05:20:39 4161 8192000000 9997184400 10388496384 1081190016 11.659 100 38 76 1 1114980448 25551744 8238688
05:21:43 7401 8192000000 9997785312 10400210944 1085365424 11.699 100 26 52 0 1109688112 18984224 5338464
05:22:51 20307 8192000000 10018629280 10423578624 1088195840 11.726 100 39 77 0 1128798112 32245472 8356800
05:24:12 4993 8192000000 10016733008 10413887488 1094720368 11.788 100 57 115 0 1135294816 27624096 12950352
05:25:20 6170 8192000000 10018097568 10418135040 1098756944 11.826 100 30 57 3 1123421376 18519904 6144528
05:26:29 5945 8192000000 10027361472 10426052608 1094684320 11.787 99 46 89 3 1130089600 25696832 9708448
05:28:08 7747 8192000000 10012327376 10427187200 1098365392 11.822 100 39 79 0 1137212128 30172928 8673808
05:29:17 5717 8192000000 9999662128 10401300480 1056657936 11.424 97 39 78 0 1086333248 20651392 9023920
05:30:26 6403 8192000000 9956432320 10341203968 1011166176 10.987 97 42 85 0 1032393744 10992288 10235280
05:31:34 33299 8192000000 9913248864 10294923264 938191760 10.275 91 25 49 0 958412672 14431728 5789184
05:33:08 4637 8192000000 9963636384 10335154176 1009174560 10.967 99 57 114 0 1060972416 40203296 11594560
05:34:13 6462 8192000000 9957684768 10338299904 1043922496 11.302 100 45 91 1 1062313984 7873616 10517872
05:35:23 10433 8192000000 9985301344 10382151680 1062624672 11.482 100 30 60 1 1088033408 18467312 6941424
05:36:36 22570 8192000000 9985775424 10398347264 1065665440 11.511 100 27 54 0 1101099952 29944304 5490208
05:38:08 13885 8192000000 10024780160 10417283072 1097421056 11.813 100 57 108 1 1131714688 23708816 10584816
05:39:35 18026 8192000000 10094771680 10488700928 1117031648 11.999 100 52 104 0 1198413088 70637840 10743600
05:40:55 2507 8192000000 10124270352 10521989120 1131368336 12.134 100 66 134 1 1229182960 83490544 14324080
05:42:01 57868 8192000000 10182668528 10602901504 1143732160 12.251 100 29 58 0 1280275232 130130848 6412224
05:44:02 3761 8192000000 10242270896 10640424960 1156094048 12.367 100 69 137 2 1340395200 170126096 14175056
05:45:09 6611 8192000000 10273638560 10676813824 1163002128 12.431 100 69 138 0 1374494368 196515840 14976400
05:46:23 6612 8192000000 10271754080 10689966080 1166908992 12.468 100 68 134 2 1374000016 192561456 14529568
05:47:36 1161 8192000000 10326767968 10726592512 1180096624 12.591 100 75 148 0 1424193120 227893568 16202928
05:48:41 2757 8192000000 10355626352 10760024064 1176350752 12.556 99 73 146 0 1454382160 261403888 16627520
05:49:49 2109 8192000000 10404448432 10811277312 1196706016 12.746 100 83 163 0 1503157168 287867472 18583680
05:50:55 4565 8192000000 10414592544 10822946816 1186758112 12.653 100 84 167 1 1516650736 311654832 18237792
05:52:09 1627 8192000000 10461994384 10867048448 1197842880 12.756 100 70 139 0 1555642560 341303456 16496224
05:53:16 4636 8192000000 10519134928 10925531136 1198066832 12.758 100 81 160 0 1611688720 394733120 18888768
05:54:25 1999 8192000000 10534533040 10935803904 1191841536 12.700 100 91 178 1 1633845776 420960416 21043824
05:55:34 4456 8192000000 10582768800 10990657536 1169798400 12.495 100 82 162 0 1679395936 489014656 20582880
05:56:48 2960 8192000000 10573160304 10980704256 1171747024 12.513 100 77 152 0 1668020976 477828144 18445808
05:57:57 5363 8192000000 10640342496 11054030848 1171937776 12.515 99 73 146 0 1726792272 535925952 18928544
05:59:05 14362 8192000000 10568805264 11087032320 1150536400 12.315 98 47 94 0 1653640528 490451120 12653008
06:00:26 4543 8192000000 10536734752 10971185152 1166043280 12.460 100 45 88 1 1632049920 454097600 11909040
06:01:46 15883 8192000000 10459997536 10901491712 1145049744 12.263 99 33 66 0 1542137216 388032816 9054656
06:03:03 23763 8192000000 10450839664 10958446592 1135553648 12.174 100 25 50 0 1586490464 443781440 7155376
06:04:33 31000 8192000000 10186098288 10690330624 1110815520 11.940 98 11 22 1 1303535280 190018752 2701008
06:07:21 110267 8192000000 10038594512 10480984064 1100939504 11.847 99 34 66 2 1147293680 39308448 7045728
06:10:17 53537 8192000000 10058340080 10499616768 1107031424 11.904 98 29 58 0 1156339392 43882848 5425120
06:12:19 77802 8192000000 10033108592 10475679744 1093532240 11.776 97 15 30 1 1115679680 19323760 2823680
06:14:47 51058 8192000000 10060143840 10506985472 1102791232 11.864 98 21 42 1 1136138224 28677584 4669408
06:16:55 49641 8192000000 10105745728 10552266752 1127712976 12.100 99 24 48 1 1201244016 68071952 5459088
06:18:45 75502 8192000000 10121847152 10586873856 1142252688 12.237 100 20 40 0 1224582592 77961856 4368048
06:22:29 40171 8192000000 10099068368 10563944448 1129773600 12.119 100 26 52 0 1206149440 71102528 5273312
06:24:13 44438 8192000000 10117138160 10558980096 1135958528 12.177 100 21 42 1 1212105968 71440416 4707024
06:26:00 80127 8192000000 10091468144 10524094464 1125538192 12.079 99 13 26 0 1223581520 95071216 2972112
06:28:23 78384 8192000000 10060146128 10535927808 1119676864 12.024 98 20 39 1 1182492048 58096128 4719056
06:30:42 104680 8192000000 10158560528 10613673984 1132768352 12.147 99 26 53 0 1237910976 99840560 5302064
06:33:31 59377 8192000000 10070160960 10509262848 1135153072 12.170 99 35 65 1 1194192240 52263376 6775792
06:35:47 63548 8192000000 10106229168 10571350016 1149145808 12.301 100 22 44 0 1194512992 40871792 4495392
06:38:12 89306 8192000000 10162199088 10632261632 1150214496 12.312 99 14 28 0 1236546528 82913312 3418720
06:40:56 55809 8192000000 10140451968 10608885760 1116792848 11.997 97 19 38 0 1207959184 86767904 4398432
06:43:05 51044 8192000000 10102327008 10553163776 1108607952 11.919 96 17 34 0 1160436224 48362208 3466064
06:45:00 48520 8192000000 10122699680 10580856832 1132049392 12.141 99 13 26 3 1212943344 78074784 2819168
06:46:52 98979 8192000000 10066332832 10492891136 1079422976 11.642 94 20 40 0 1117668464 34115648 4129840
06:49:34 32861 8192000000 10096942320 10548838400 1108144112 11.915 98 32 62 3 1179562896 64491520 6927264
06:51:10 51938 8192000000 10128457008 10611421184 1120232320 12.029 99 28 56 0 1206429920 80460560 5737040
06:53:02 24014 8192000000 10126872752 10545434624 1127102112 12.094 100 46 92 2 1212211024 75687392 9421520
06:54:30 124475 8192000000 10045824224 10477494272 1088001536 11.724 97 25 49 2 1137218688 43968768 5248384
06:57:36 45864 8192000000 10020402880 10475020288 1087352480 11.717 98 8 16 0 1132480912 43242336 1886096
06:59:25 54884 8192000000 10043579472 10518978560 1094097584 11.782 99 9 18 0 1146608656 50846608 1664464
07:01:50 46185 8192000000 10076284576 10529308672 1125913024 12.083 100 20 40 0 1187845952 57844528 4088400
07:03:41 84997 8192000000 10103949696 10543022080 1105292944 11.888 98 23 46 0 1182336704 71246544 5797216
07:06:23 33718 8192000000 10101281152 10548555776 1122467792 12.050 99 32 63 0 1173068304 44165120 6435392
07:08:00 64197 8192000000 10053510416 10479435776 1063884720 11.494 95 33 66 1 1100675952 30490432 6300800
07:10:05 74602 8192000000 10079104960 10540797952 1101492096 11.852 98 14 26 1 1143042304 39043280 2506928
07:12:26 117484 8192000000 10076963056 10590986240 1087367024 11.718 96 17 34 1 1133569552 43090960 3111568
07:15:34 95815 8192000000 10064958528 10536751104 1122586288 12.051 100 19 38 1 1157477408 30738768 4152352
07:18:12 60845 8192000000 10036986096 10500653056 1116984272 11.998 99 9 18 0 1155409872 36580512 1845088
07:20:13 76475 8192000000 10063236112 10527174656 1119339920 12.021 99 17 33 0 1162512448 39949936 3222592
07:22:40 25643 8192000000 10061618768 10504454144 1119908992 12.026 99 27 55 1 1144983232 20063920 5010320
07:24:08 29249 8192000000 10112769808 10568085504 1146311920 12.275 100 31 62 1 1207968368 55046768 6609680
07:26:09 45821 8192000000 10171935616 10639802368 1156639776 12.372 100 25 49 2 1262908432 100424832 5843824
07:27:55 89021 8192000000 10129499264 10605342720 1129434832 12.116 97 26 52 0 1283473552 148110960 5927760
07:30:24 43636 8192000000 10148706496 10605039616 1131303824 12.134 97 36 72 0 1228316704 89472992 7539888
07:32:11 62657 8192000000 10074712000 10509692928 1112846096 11.959 97 30 58 4 1178513248 59603840 6063312
07:34:16 112953 8192000000 10087275264 10585341952 1126991104 12.093 100 22 44 0 1214357360 82801264 4564992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2021 07:40:04 to 31-12-2021 07:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 07:40:20 to 31-12-2021 07:40:22.

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          226588
MON$OLDEST_ACTIVE               227674
MON$OLDEST_SNAPSHOT             227674
MON$NEXT_TRANSACTION            227674
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                       2037280
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:162: 
:163: mscs-test-161.budzdorov.ru    Fri Dec 31 07:37:22 2021
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Fri Dec 31 07:37:23 2021
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:169: 
--- end of diff output ---

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

31.12.21 07:40:23. 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-12-31 07:40:26 No severe PSQL-related problems occured
31.12.21 07:40:27. End of report.