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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16432488 kB
MemAvailable 31448132 kB
Buffers 20 kB
Cached 15226064 kB
SwapCached 0 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-16 04:37:11
DTS_END                         2021-12-16 04:37:11.0020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58642
TRN_ID                          225607
ATT_ID                          654
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: 8963.17 30773 1075581
customer order: creation 160.19 388 19223
customer order: refuse 14.21 388 1705
order to supplier: creation 43.89 1472 5267
order to supplier: removal 3.18 139735 382
invoice (draft): creation 43.69 4143 5243
invoice (draft): removal 50.91 1056 6109
invoice accept: apply 39.93 24043 4792
invoice accept: cancel 46.86 999 5623
customer reserve: creation 3699.07 238 443888
customer reserve: removal 4572.33 74 548679
realization accept: apply 79.64 218 9557
realization accept: cancel 85.82 128 10298
payment from customer: creation 57.40 122 6888
payment from customer: removal 5.43 130 652
payment to supplier: creation 47.13 137 5655
payment to supplier: removal 3.17 91 380
service: total inventory turnovers 3.53 4446 424
service: total monetary turnovers 6.13 783 735
service: aggregate perf. data 0.64 25202 77
service: refresh index statistics 0.03 411671 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2021 04:38:58 to 16-12-2021 04:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5431
TEST_TIME 2 6542
TEST_TIME 3 5862
TEST_TIME 4 5649
TEST_TIME 5 5348
TEST_TIME 6 5455
TEST_TIME 7 5889
TEST_TIME 8 5870
TEST_TIME 9 5898
TEST_TIME 10 6617
TEST_TIME 11 6753
TEST_TIME 12 7197
TEST_TIME 13 7355
TEST_TIME 14 7432
TEST_TIME 15 7877
TEST_TIME 16 7988
TEST_TIME 17 8119
TEST_TIME 18 8349
TEST_TIME 19 8329
TEST_TIME 20 8279
TEST_TIME 21 8496
TEST_TIME 22 8482
TEST_TIME 23 8350
TEST_TIME 24 8301
TEST_TIME 25 8696
TEST_TIME 26 8859
TEST_TIME 27 9032
TEST_TIME 28 9141
TEST_TIME 29 9870
TEST_TIME 30 10509
TEST_TIME 31 10892
TEST_TIME 32 11466
TEST_TIME 33 11401
TEST_TIME 34 11196
TEST_TIME 35 10945
TEST_TIME 36 10851
TEST_TIME 37 10890
TEST_TIME 38 10767
TEST_TIME 39 10727
TEST_TIME 40 10605
TEST_TIME 41 10500
TEST_TIME 42 10376
TEST_TIME 43 10280
TEST_TIME 44 10401
TEST_TIME 45 10376
TEST_TIME 46 10261
TEST_TIME 47 10361
TEST_TIME 48 10283
TEST_TIME 49 10213
TEST_TIME 50 10164
TEST_TIME 51 10122
TEST_TIME 52 9961
TEST_TIME 53 9920
TEST_TIME 54 9834
TEST_TIME 55 9734
TEST_TIME 56 9701
TEST_TIME 57 9789
TEST_TIME 58 9748
TEST_TIME 59 9738
TEST_TIME 60 9722
TEST_TIME 61 9839
TEST_TIME 62 9761
TEST_TIME 63 9752
TEST_TIME 64 9714
TEST_TIME 65 9651
TEST_TIME 66 9624
TEST_TIME 67 9676
TEST_TIME 68 9655
TEST_TIME 69 9730
TEST_TIME 70 9712
TEST_TIME 71 9622
TEST_TIME 72 9549
TEST_TIME 73 9537
TEST_TIME 74 9492
TEST_TIME 75 9492
TEST_TIME 76 9437
TEST_TIME 77 9497
TEST_TIME 78 9442
TEST_TIME 79 9420
TEST_TIME 80 9416
TEST_TIME 81 9458
TEST_TIME 82 9499
TEST_TIME 83 9438
TEST_TIME 84 9450
TEST_TIME 85 9468
TEST_TIME 86 9475
TEST_TIME 87 9588
TEST_TIME 88 9553
TEST_TIME 89 9460
TEST_TIME 90 9430
TEST_TIME 91 9376
TEST_TIME 92 9359
TEST_TIME 93 9300
TEST_TIME 94 9275
TEST_TIME 95 9288
TEST_TIME 96 9282
TEST_TIME 97 9219
TEST_TIME 98 9178
TEST_TIME 99 9211
TEST_TIME 100 9235
TEST_TIME 101 9253
TEST_TIME 102 9255
TEST_TIME 103 9254
TEST_TIME 104 9218
TEST_TIME 105 9259
TEST_TIME 106 9299
TEST_TIME 107 9308
TEST_TIME 108 9307
TEST_TIME 109 9322
TEST_TIME 110 9316
TEST_TIME 111 9308
TEST_TIME 112 9235
TEST_TIME 113 9228
TEST_TIME 114 9176
TEST_TIME 115 9152
TEST_TIME 116 9104
TEST_TIME 117 9055
TEST_TIME 118 8996
TEST_TIME 119 9027
TEST_TIME 120 8972

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2021 04:39:02 to 16-12-2021 04:39:05.

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 1486181 1486181 0 0.00 0 38686 9 0 0
doc_list_biud 1486177 1486177 0 0.00 0 22668 35 0 0
sp_add_invoice_to_stock 4956 4792 164 3.30 55 257824 24044 0 164
sp_cancel_adding_invoice 5623 5623 0 0.00 5 66056 1000 0 0
sp_cancel_client_order 1705 1705 0 0.00 4 8801 388 0 0
sp_cancel_customer_reserve 548679 548679 0 0.00 1 22672 75 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 9959 130 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 2078 92 0 0
sp_cancel_supplier_invoice 6124 6109 15 0.24 2 18275 1057 0 15
sp_cancel_supplier_order 382 382 0 0.00 7 1257715 139736 0 0
sp_cancel_write_off 10298 10298 0 0.00 1 16370 129 0 0
sp_client_order 19223 19223 0 0.00 4 28754 388 0 0
sp_customer_reserve 443888 443888 0 0.00 0 150979 238 0 0
sp_fill_shopping_cart 29719 29719 0 0.00 1 17772 43 0 0
sp_fill_shopping_cart_clo_res 443876 443876 0 0.00 0 15614 16 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 20117 332 0 0
sp_kill_cost_storno 16953 16953 0 0.00 0 9643 72 0 0
sp_kill_qstorno_ret_qs2qd 1114147 1114147 0 0.00 0 22665 40 0 0
sp_kill_qty_storno 587129 587129 0 0.00 0 22668 87 0 0
sp_lock_selected_doc 19827 19827 0 0.00 0 7559 4 0 0
sp_make_cost_storno 26877 26877 0 0.00 0 38681 63 0 0
sp_multiply_rows_for_pdistr 26876 26876 0 0.00 0 9788 38 0 0
sp_multiply_rows_for_qdistr 19223 19223 0 0.00 0 28741 189 0 0
sp_pay_from_customer 6888 6888 0 0.00 4 11066 122 0 0
sp_pay_to_supplier 5655 5655 0 0.00 5 12778 138 0 0
sp_payment_common 12543 12543 0 0.00 1 10029 20 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 8796 350 0 0
sp_qd_handle_on_invoice_upd_sts 10400 10400 0 0.00 0 16582 454 0 0
sp_qd_handle_on_reserve_upd_sts 9556 9556 0 0.00 0 21790 72 0 0
sp_reserve_write_off 9557 9557 0 0.00 7 21835 219 0 0
sp_supplier_invoice 5243 5243 0 0.00 7 89015 4144 0 0
sp_supplier_order 5267 5267 0 0.00 4 27190 1472 0 0
srv_aggregate_perf_data 77 77 0 0.00 2983 357602 25202 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1603 129376 42958 0 0
srv_find_qd_qs_mism 1029876 1029876 0 0.00 0 19998 8 0 0
srv_make_invnt_saldo 424 424 0 0.00 19 109249 4446 0 0
srv_make_money_saldo 735 735 0 0.00 1 16042 783 0 0
srv_recalc_idx_stat 4 4 0 0.00 310527 509547 411672 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4792 4792 0 0.00 0 19438 222 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 5577 161 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 4582 15 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 15068 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 1459 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 4168 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 10527 48 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 22909 2011 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 2099 10 0 0
t$perf-norm:sp_client_order 19223 19223 0 0.00 0 14070 27 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 1502 7 0 0
t$perf-norm:sp_pay_from_customer 6888 6888 0 0.00 0 5400 12 0 0
t$perf-norm:sp_pay_to_supplier 5655 5655 0 0.00 0 6111 11 0 0
t$perf-norm:sp_reserve_write_off 9557 9557 0 0.00 0 11626 13 0 0
t$perf-norm:sp_supplier_invoice 5243 5243 0 0.00 0 11105 27 0 0
t$perf-norm:sp_supplier_order 5267 5267 0 0.00 0 10222 24 0 0
t$perf-norm:srv_aggregate_perf_data 77 77 0 0.00 0 442 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 623 14 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 4169 29 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 11429 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 127 34 0 0
v_add_invoice_to_stock 9882 9554 328 3.31 0 6928 19 0 328
v_all_customers 35780 35780 0 0.00 0 18 1 0 0
v_all_suppliers 29404 29404 0 0.00 0 33 1 0 0
v_all_wares 38440 38440 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1147 11 0 0
v_cancel_client_order 3410 3410 0 0.00 0 6708 11 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 4363 10 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 1230 6 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1946 10 0 0
v_cancel_supplier_invoice 756 726 30 3.96 0 1626 18 0 30
v_cancel_supplier_order 764 764 0 0.00 0 204 4 0 0
v_cancel_write_off 1980 1980 0 0.00 0 5231 19 0 0
v_max_id_clo_ord 5264 5264 0 0.00 0 398 1 0 0
v_max_id_clo_res 929 929 0 0.00 0 6594 17 0 0
v_max_id_ord_sup 5234 5234 0 0.00 0 2732 4 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 46 2 0 0
v_max_non_paid_realizn 3542 3542 0 0.00 0 8228 5 0 0
v_min_id_clo_ord 5264 5264 0 0.00 0 1749 7 0 0
v_min_id_clo_res 929 929 0 0.00 0 3458 13 0 0
v_min_id_ord_sup 5234 5234 0 0.00 0 8160 8 0 0
v_min_non_paid_invoice 5655 5655 0 0.00 0 9680 12 0 0
v_min_non_paid_realizn 6888 6888 0 0.00 0 5417 13 0 0
v_our_firm 9352 9352 0 0.00 0 19 1 0 0
v_reserve_write_off 19112 19112 0 0.00 0 5457 8 0 0
x_lock_depdocs_on_canc_invoice 11369 11369 0 0.00 0 44759 152 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 14346 456 0 0
x_make_qstorno_1000_1200 5267 5267 0 0.00 1 27168 1365 0 0
x_make_qstorno_1200_2000 5243 5243 0 0.00 4 88991 4001 0 0
x_make_qstorno_2100_3300 443887 443887 0 0.00 0 150978 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 04:39:06 to 16-12-2021 04:39:10.

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
02:07:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 31035 8192000000 9726692144 10025529344 802085792 8.917 100 86 171 2 831105120 8019824 20999504
02:09:51 28533 8192000000 9790092432 10101379072 858332384 9.483 100 87 174 0 895694704 17019584 20342736
02:11:24 25520 8192000000 9828731872 10152759296 893426944 9.833 100 87 174 2 936645520 22844720 20373856
02:12:55 12574 8192000000 9881407696 10217926656 930821280 10.203 100 75 150 0 986397424 39312736 16263408
02:14:11 11064 8192000000 9951427872 10297864192 974400080 10.630 100 81 162 0 1054324080 63426096 16497904
02:15:28 5342 8192000000 9983326752 10344730624 1001908512 10.897 100 81 162 0 1100903936 82544176 16451248
02:16:40 3057 8192000000 10003658144 10369576960 1032484560 11.192 100 78 155 0 1117187488 69332272 15370656
02:17:45 2572 8192000000 10033019856 10403721216 1055620624 11.415 100 88 175 1 1142660464 69402096 17637744
02:18:51 3296 8192000000 10025279776 10398064640 1062890080 11.484 100 83 167 0 1131260048 51054544 17315424
02:19:57 28141 8192000000 10036908112 10412113920 1072447408 11.575 100 67 135 0 1149188736 62944000 13797328
02:21:27 27362 8192000000 10017689488 10422226944 1078879568 11.637 100 43 85 0 1130585280 42743408 8962304
02:22:59 5001 8192000000 9987887264 10382004224 1086261088 11.707 100 38 74 3 1102998048 7947968 8788992
02:24:05 2831 8192000000 10003520896 10395578368 1095476832 11.795 100 42 84 0 1125442688 19563536 10402320
02:25:09 51860 8192000000 10007086208 10418503680 1096750864 11.807 100 33 64 2 1114651744 10095824 7805056
02:27:06 7125 8192000000 10022048768 10433413120 1102403216 11.860 100 43 86 0 1130970112 18816992 9749904
02:28:16 9160 8192000000 10021259488 10430849024 1085370384 11.699 99 38 76 1 1122407344 28788416 8248544
02:29:29 11059 8192000000 9988673056 10394324992 1048552848 11.347 97 26 52 1 1091495248 37733968 5208432
02:30:42 40447 8192000000 9939150992 10325348352 932138640 10.216 87 23 46 0 952370736 15990480 4241616
02:32:23 6027 8192000000 9934652224 10307366912 967130608 10.559 94 39 78 2 995859536 20985568 7743360
02:33:32 3361 8192000000 9955876480 10332426240 1033381792 11.201 100 44 88 1 1059208096 15953120 9873184
02:34:36 70737 8192000000 9953864192 10352869376 1050336096 11.364 100 27 53 0 1076441072 20502864 5602112
02:36:50 4741 8192000000 9973676064 10364702720 1064673440 11.501 100 43 83 4 1089697408 16765408 8258560
02:38:06 28250 8192000000 10012136704 10407555072 1078753296 11.636 100 45 90 1 1117071440 29203920 9114224
02:39:45 5884 8192000000 10071377328 10469462016 1100860256 11.846 100 56 112 0 1183170288 70361120 11948912
02:40:54 24824 8192000000 10093099696 10503397376 1111161424 11.943 100 40 80 0 1199503104 79803984 8537696
02:42:23 5612 8192000000 10147870848 10546397184 1117632784 12.005 100 61 120 0 1254344128 123268688 13442656
02:43:46 5368 8192000000 10182583904 10574286848 1138502896 12.201 100 72 144 2 1289785088 134999792 16282400
02:44:52 7216 8192000000 10216512160 10624503808 1141802480 12.232 100 56 108 0 1334591056 180454080 12334496
02:46:07 4996 8192000000 10248706144 10657402880 1147589360 12.287 100 62 122 1 1358751632 197222272 13940000
02:47:17 8372 8192000000 10251961056 10679324672 1165791840 12.457 100 53 105 0 1355793696 177934608 12067248
02:48:37 19644 8192000000 10263457856 10718900224 1180821808 12.598 100 55 110 0 1375233312 182667072 11744432
02:50:02 4164 8192000000 10308197424 10715533312 1192642016 12.708 100 74 148 0 1413616720 204962960 16011744
02:51:11 4825 8192000000 10319563504 10727030784 1191141440 12.694 100 72 144 1 1418820784 212016544 15662800
02:52:22 3416 8192000000 10314963552 10738122752 1182473792 12.613 99 76 151 2 1408310368 208730288 17106288
02:53:33 3144 8192000000 10326777872 10736128000 1179048464 12.581 100 78 151 0 1425003280 229716176 16238640
02:54:40 4570 8192000000 10339837328 10748174336 1185189200 12.639 100 73 146 0 1439214608 237595008 16430400
02:55:50 2230 8192000000 10381811536 10784743424 1193947904 12.720 100 91 181 0 1476963040 262927872 20087264
02:56:57 1601 8192000000 10418010976 10816491520 1188571408 12.670 100 93 186 1 1515319872 305779712 20968752
02:58:07 2055 8192000000 10452100928 10852433920 1181545024 12.605 100 96 193 1 1545076096 340901152 22629920
02:59:14 6195 8192000000 10461155136 10887098368 1179995216 12.590 100 51 98 2 1553050896 360941440 12114240
03:00:26 6682 8192000000 10483140688 10904375296 1151890576 12.327 99 43 86 1 1580062880 416991968 11180336
03:01:49 3138 8192000000 10546993648 10979913728 1160096848 12.404 99 64 127 0 1653248992 477247952 15904192
03:03:00 15983 8192000000 10525295744 10998984704 1176054464 12.553 100 46 92 0 1636087168 448029408 12003296
03:04:25 4552 8192000000 10552634016 10977677312 1162385968 12.426 100 58 116 1 1646502448 468823552 15292928
03:05:39 2063 8192000000 10573763440 11007488000 1162635040 12.428 100 79 158 1 1668732592 487189104 18908448
03:06:45 17261 8192000000 10373692592 10941255680 1159582832 12.399 100 31 64 0 1573537872 406108560 7846480
03:08:04 28063 8192000000 10216328112 10754203648 1124165472 12.066 99 9 18 0 1331408272 204853184 2389616
03:09:40 45267 8192000000 10079927024 10520629248 1118379616 12.012 99 14 28 0 1177414176 56152784 2881776
03:11:25 89089 8192000000 10046129616 10477867008 1070098960 11.553 95 38 76 1 1099671296 21010592 8561744
03:14:07 97363 8192000000 10083954320 10518343680 1114526960 11.975 99 34 67 0 1189340272 67423712 7389600
03:16:45 73324 8192000000 10096878160 10509996032 1125381520 12.078 99 60 119 4 1172691920 34137360 13173040
03:19:00 22657 8192000000 10172371648 10596495360 1131533904 12.136 99 36 71 2 1243327072 104292128 7501040
03:20:28 61128 8192000000 10221251264 10678845440 1140483456 12.220 99 29 58 0 1303600128 155791664 7325008
03:22:39 63040 8192000000 10140539056 10597498880 1130046400 12.122 99 19 39 0 1231832624 97570064 4216160
03:24:44 129376 8192000000 10063441920 10529542144 1120289312 12.030 99 9 18 0 1142933696 20658528 1985856
03:28:08 76290 8192000000 10063684688 10555420672 1129837744 12.120 99 18 36 0 1174733600 40592736 4303120
03:30:29 59850 8192000000 10091942512 10558779392 1136790800 12.185 99 16 31 0 1202801184 62750480 3259904
03:32:43 46673 8192000000 10114311936 10589704192 1155425600 12.360 100 18 36 1 1238203632 79160896 3617136
03:34:39 65399 8192000000 10114458080 10622771200 1119454368 12.022 97 13 25 1 1233106272 110782976 2868928
03:36:58 57749 8192000000 10112240320 10569924608 1111600432 11.948 97 29 58 0 1190082176 72463776 6017968
03:38:57 35509 8192000000 10077153296 10524323840 1141761488 12.232 100 22 44 1 1167206720 20679088 4766144
03:40:40 62474 8192000000 10073807552 10523123712 1116229808 11.991 99 29 58 0 1159833408 37845552 5758048
03:42:47 10354 8192000000 10089936656 10517209088 1119061568 12.018 99 55 109 2 1180006512 50325056 10619888
03:43:59 106789 8192000000 10149433504 10628190208 1129638640 12.118 100 25 50 0 1185405184 50958064 4808480
03:46:46 109168 8192000000 10083426480 10519834624 1137862720 12.195 100 29 57 0 1192075488 48760704 5452064
03:49:42 36162 8192000000 10093942912 10529366016 1139901424 12.215 100 40 80 0 1198024144 49630080 8492640
03:51:19 65152 8192000000 10119559488 10595635200 1135936704 12.177 99 15 30 0 1207110416 67979440 3194272
03:53:25 80271 8192000000 10066628032 10564399104 1109233136 11.925 97 12 24 0 1169685648 57942752 2509760
03:55:58 34230 8192000000 10086521888 10512576512 1108792800 11.921 99 53 106 0 1176524144 56289024 11442320
03:57:33 99806 8192000000 10099427520 10563051520 1106524368 11.899 98 17 34 1 1181766976 72123344 3119264
04:00:15 37841 8192000000 10095660864 10525138944 1113221952 11.963 98 29 58 3 1145910976 26950624 5738400
04:01:56 103706 8192000000 10090509392 10561437696 1063181152 11.487 94 17 35 1 1131157296 64762016 3214128
04:04:40 66554 8192000000 10073381520 10497564672 1132194896 12.142 100 26 51 2 1182987584 45291248 5501440
04:07:03 91228 8192000000 10060782384 10506137600 1083232928 11.678 96 33 67 0 1126737120 36797184 6707008
04:09:34 55243 8192000000 10073118576 10552352768 1103435392 11.870 98 9 18 1 1174134448 68842960 1856096
04:12:13 112870 8192000000 10074663824 10542944256 1126475840 12.088 100 14 28 2 1210806080 80897008 3433232
04:15:06 52876 8192000000 10090287840 10555310080 1104129424 11.877 97 10 20 0 1179067584 72702896 2235264
04:17:21 69543 8192000000 10066818752 10561097728 1109177296 11.925 98 10 20 2 1179720592 68503168 2040128
04:20:07 60696 8192000000 10078729600 10528702464 1107069904 11.905 98 23 46 0 1150515936 38709056 4736976
04:22:12 35090 8192000000 10104306192 10551668736 1130059616 12.122 100 34 68 2 1214731328 78099520 6572192
04:23:47 42081 8192000000 10082916288 10517073920 1121581888 12.042 99 34 68 2 1170395744 42485184 6328672
04:25:37 20512 8192000000 10111977296 10538168320 1129326128 12.115 99 40 80 0 1192552112 55597040 7628944
04:27:33 9399 8192000000 10090252144 10508763136 1121338496 12.040 100 49 98 0 1171805392 40941984 9524912
04:28:46 86508 8192000000 10082782544 10541105152 1113819696 11.969 99 34 68 0 1194342128 73902816 6619616
04:31:26 60131 8192000000 10066564128 10535387136 1117999328 12.008 99 6 12 0 1151876336 32823232 1053776
04:33:30 74938 8192000000 10071270768 10531278848 1130798544 12.129 100 12 24 1 1168362720 35227184 2336992
04:35:48 65639 8192000000 10088069184 10580381696 1126287696 12.086 99 36 72 1 1221599168 87269088 8042384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-12-2021 04:39:12 to 16-12-2021 04:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 04:39:27 to 16-12-2021 04:39:29.

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          225434
MON$OLDEST_ACTIVE               225849
MON$OLDEST_SNAPSHOT             225849
MON$NEXT_TRANSACTION            225849
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                       2055712
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2689 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2689 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2689 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2689 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Dec 16 04:37:17 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
--- end of diff output ---

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

16.12.21 04:39:30. 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-16 04:39:33 No severe PSQL-related problems occured
16.12.21 04:39:34. End of report.