Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2021 07:50
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 16696732 kB
MemAvailable 31534368 kB
Buffers 0 kB
Cached 15025756 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-07 07:48:11
DTS_END                         2021-12-07 07:48:11.4540
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/32986
TRN_ID                          224216
ATT_ID                          592
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: 9281.39 24900 1113767
customer order: creation 163.96 441 19675
customer order: refuse 14.44 337 1733
order to supplier: creation 43.08 1553 5170
order to supplier: removal 3.59 114930 431
invoice (draft): creation 43.58 3616 5230
invoice (draft): removal 52.98 914 6357
invoice accept: apply 41.11 19707 4933
invoice accept: cancel 48.57 871 5828
customer reserve: creation 3824.70 193 458964
customer reserve: removal 4759.31 69 571117
realization accept: apply 79.29 198 9515
realization accept: cancel 86.24 132 10349
payment from customer: creation 55.99 116 6719
payment from customer: removal 5.29 96 635
payment to supplier: creation 45.55 124 5466
payment to supplier: removal 3.14 64 377
service: total inventory turnovers 3.77 3767 452
service: total monetary turnovers 5.91 569 709
service: aggregate perf. data 0.86 17541 103
service: refresh index statistics 0.03 332755 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2021 07:50:52 to 07-12-2021 07:50:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6342
TEST_TIME 2 6372
TEST_TIME 3 6064
TEST_TIME 4 5978
TEST_TIME 5 5405
TEST_TIME 6 5474
TEST_TIME 7 5382
TEST_TIME 8 5790
TEST_TIME 9 6420
TEST_TIME 10 6318
TEST_TIME 11 6749
TEST_TIME 12 6786
TEST_TIME 13 7140
TEST_TIME 14 7773
TEST_TIME 15 8208
TEST_TIME 16 8090
TEST_TIME 17 8490
TEST_TIME 18 8824
TEST_TIME 19 8678
TEST_TIME 20 8841
TEST_TIME 21 8862
TEST_TIME 22 9023
TEST_TIME 23 9467
TEST_TIME 24 9548
TEST_TIME 25 10489
TEST_TIME 26 11853
TEST_TIME 27 11509
TEST_TIME 28 11278
TEST_TIME 29 11231
TEST_TIME 30 11073
TEST_TIME 31 10750
TEST_TIME 32 10714
TEST_TIME 33 10566
TEST_TIME 34 10424
TEST_TIME 35 10366
TEST_TIME 36 10211
TEST_TIME 37 10228
TEST_TIME 38 10231
TEST_TIME 39 10406
TEST_TIME 40 10256
TEST_TIME 41 10147
TEST_TIME 42 9986
TEST_TIME 43 10108
TEST_TIME 44 10071
TEST_TIME 45 10120
TEST_TIME 46 10031
TEST_TIME 47 9990
TEST_TIME 48 9977
TEST_TIME 49 9994
TEST_TIME 50 10006
TEST_TIME 51 9966
TEST_TIME 52 10100
TEST_TIME 53 10257
TEST_TIME 54 10278
TEST_TIME 55 10225
TEST_TIME 56 10205
TEST_TIME 57 10067
TEST_TIME 58 9987
TEST_TIME 59 9908
TEST_TIME 60 9877
TEST_TIME 61 9864
TEST_TIME 62 9793
TEST_TIME 63 9666
TEST_TIME 64 9627
TEST_TIME 65 9611
TEST_TIME 66 9621
TEST_TIME 67 9625
TEST_TIME 68 9638
TEST_TIME 69 9641
TEST_TIME 70 9630
TEST_TIME 71 9614
TEST_TIME 72 9577
TEST_TIME 73 9638
TEST_TIME 74 9695
TEST_TIME 75 9665
TEST_TIME 76 9722
TEST_TIME 77 9750
TEST_TIME 78 9637
TEST_TIME 79 9557
TEST_TIME 80 9548
TEST_TIME 81 9537
TEST_TIME 82 9506
TEST_TIME 83 9432
TEST_TIME 84 9439
TEST_TIME 85 9470
TEST_TIME 86 9431
TEST_TIME 87 9388
TEST_TIME 88 9404
TEST_TIME 89 9419
TEST_TIME 90 9414
TEST_TIME 91 9434
TEST_TIME 92 9559
TEST_TIME 93 9549
TEST_TIME 94 9624
TEST_TIME 95 9572
TEST_TIME 96 9668
TEST_TIME 97 9654
TEST_TIME 98 9568
TEST_TIME 99 9489
TEST_TIME 100 9493
TEST_TIME 101 9458
TEST_TIME 102 9416
TEST_TIME 103 9369
TEST_TIME 104 9337
TEST_TIME 105 9326
TEST_TIME 106 9329
TEST_TIME 107 9288
TEST_TIME 108 9297
TEST_TIME 109 9272
TEST_TIME 110 9292
TEST_TIME 111 9279
TEST_TIME 112 9367
TEST_TIME 113 9357
TEST_TIME 114 9417
TEST_TIME 115 9387
TEST_TIME 116 9451
TEST_TIME 117 9479
TEST_TIME 118 9434
TEST_TIME 119 9359
TEST_TIME 120 9283

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-12-2021 07:50:55 to 07-12-2021 07:50:59.

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 1538811 1538811 0 0.00 0 14843 7 0 0
doc_list_biud 1538811 1538811 0 0.00 0 83569 32 0 0
sp_add_invoice_to_stock 5073 4933 140 2.75 52 199622 19707 0 140
sp_cancel_adding_invoice 5828 5828 0 0.00 5 61356 872 0 0
sp_cancel_client_order 1733 1733 0 0.00 5 8048 337 0 0
sp_cancel_customer_reserve 571117 571117 0 0.00 1 27221 69 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 5444 97 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 2205 64 0 0
sp_cancel_supplier_invoice 6366 6357 9 0.14 2 83572 915 0 9
sp_cancel_supplier_order 431 431 0 0.00 12 1161365 114931 0 0
sp_cancel_write_off 10349 10349 0 0.00 1 16226 133 0 0
sp_client_order 19675 19675 0 0.00 4 29569 442 0 0
sp_customer_reserve 458964 458964 0 0.00 0 92884 193 0 0
sp_fill_shopping_cart 30072 30072 0 0.00 1 14777 43 0 0
sp_fill_shopping_cart_clo_res 458962 458962 0 0.00 0 15064 18 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 40655 374 0 0
sp_kill_cost_storno 17189 17189 0 0.00 0 5329 57 0 0
sp_kill_qstorno_ret_qs2qd 1159371 1159371 0 0.00 0 83566 37 0 0
sp_kill_qty_storno 610259 610259 0 0.00 0 83569 80 0 0
sp_lock_selected_doc 19975 19975 0 0.00 0 9754 5 0 0
sp_make_cost_storno 26629 26629 0 0.00 0 14464 57 0 0
sp_multiply_rows_for_pdistr 26629 26629 0 0.00 0 7498 31 0 0
sp_multiply_rows_for_qdistr 19675 19675 0 0.00 0 18966 245 0 0
sp_pay_from_customer 6719 6719 0 0.00 4 11860 117 0 0
sp_pay_to_supplier 5466 5466 0 0.00 5 7512 124 0 0
sp_payment_common 12185 12185 0 0.00 1 5628 18 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 5730 291 0 0
sp_qd_handle_on_invoice_upd_sts 10757 10757 0 0.00 0 22857 368 0 0
sp_qd_handle_on_reserve_upd_sts 9515 9515 0 0.00 0 22793 68 0 0
sp_reserve_write_off 9515 9515 0 0.00 7 22830 198 0 0
sp_supplier_invoice 5230 5230 0 0.00 8 96109 3617 0 0
sp_supplier_order 5170 5170 0 0.00 4 37505 1554 0 0
srv_aggregate_perf_data 103 103 0 0.00 3013 241660 17542 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1185 166906 48716 0 0
srv_find_qd_qs_mism 1067939 1067939 0 0.00 0 14840 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 68 98371 3767 0 0
srv_make_money_saldo 709 709 0 0.00 1 14196 570 0 0
srv_recalc_idx_stat 4 4 0 0.00 143080 479057 332755 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4933 4933 0 0.00 0 9727 152 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 4623 107 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 14311 21 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 747 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 2077 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 104 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 2982 25 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 21859 1365 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 1777 7 0 0
t$perf-norm:sp_client_order 19675 19675 0 0.00 0 10088 19 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 924 3 0 0
t$perf-norm:sp_pay_from_customer 6719 6719 0 0.00 0 15148 11 0 0
t$perf-norm:sp_pay_to_supplier 5466 5466 0 0.00 0 8927 9 0 0
t$perf-norm:sp_reserve_write_off 9515 9515 0 0.00 0 8070 8 0 0
t$perf-norm:sp_supplier_invoice 5230 5230 0 0.00 0 8818 18 0 0
t$perf-norm:sp_supplier_order 5170 5170 0 0.00 0 8705 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 66 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 534 10 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 735 7 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 488 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10138 9858 280 2.76 0 7852 23 0 280
v_all_customers 36098 36098 0 0.00 0 133 1 0 0
v_all_suppliers 28704 28704 0 0.00 0 19 1 0 0
v_all_wares 39350 39350 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 4334 14 0 0
v_cancel_client_order 3466 3466 0 0.00 0 7345 13 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 5394 12 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 7448 10 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 256 4 0 0
v_cancel_supplier_invoice 730 712 18 2.46 0 7279 43 0 18
v_cancel_supplier_order 862 862 0 0.00 0 1995 6 0 0
v_cancel_write_off 1892 1892 0 0.00 0 6047 23 0 0
v_max_id_clo_ord 5170 5170 0 0.00 0 1913 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 2047 20 0 0
v_max_id_ord_sup 5227 5227 0 0.00 0 4100 4 0 0
v_max_non_paid_invoice 1511 1511 0 0.00 0 2928 4 0 0
v_max_non_paid_realizn 3368 3368 0 0.00 0 1888 3 0 0
v_min_id_clo_ord 5170 5170 0 0.00 0 8534 7 0 0
v_min_id_clo_res 956 956 0 0.00 0 273 2 0 0
v_min_id_ord_sup 5227 5227 0 0.00 0 5652 9 0 0
v_min_non_paid_invoice 5466 5466 0 0.00 0 5339 12 0 0
v_min_non_paid_realizn 6719 6719 0 0.00 0 4216 12 0 0
v_our_firm 9954 9954 0 0.00 0 18 1 0 0
v_reserve_write_off 19030 19030 0 0.00 0 6913 7 0 0
x_lock_depdocs_on_canc_invoice 11829 11829 0 0.00 0 10839 102 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 8419 344 0 0
x_make_qstorno_1000_1200 5170 5170 0 0.00 2 37497 1476 0 0
x_make_qstorno_1200_2000 5230 5230 0 0.00 3 96096 3456 0 0
x_make_qstorno_2100_3300 458964 458964 0 0.00 0 92883 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2021 07:51:00 to 07-12-2021 07:51:04.

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:18:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:16 24385 8192000000 9716010896 10013646848 792973776 8.825 100 89 177 1 823079472 8362928 21742768
05:20:48 8610 8192000000 9749159232 10054574080 825796304 9.157 100 83 166 0 858324624 12293328 20234992
05:22:10 19798 8192000000 9806603856 10127904768 875512992 9.655 100 82 164 0 916112288 22125552 18473744
05:23:35 15474 8192000000 9873198896 10207866880 925059728 10.146 100 80 160 0 985482160 43257168 17165264
05:24:55 4988 8192000000 9920356672 10268135424 963634240 10.525 100 79 156 0 1036260624 56680608 15945776
05:26:04 4024 8192000000 9982214576 10330697728 992039088 10.801 100 84 168 1 1093301056 84376096 16885872
05:27:11 2590 8192000000 9979597664 10337189888 1015647168 11.030 100 89 179 5 1095826640 62680800 17498672
05:28:15 1664 8192000000 10007740944 10370994176 1042898288 11.293 100 86 172 0 1115434944 54907136 17629520
05:29:18 18477 8192000000 10035764128 10406060032 1059341888 11.450 100 77 151 1 1146666848 72121024 15203936
05:30:38 7158 8192000000 9989974752 10374483968 1073195008 11.583 100 40 80 2 1102119328 20514112 8410208
05:31:49 41812 8192000000 9983029216 10392891392 1079973200 11.647 100 48 95 0 1098743584 8123488 10646896
05:33:32 6776 8192000000 10001432944 10403295232 1091645392 11.758 100 31 62 2 1124428800 25560688 7222720
05:34:39 5745 8192000000 9995156352 10405871616 1093819616 11.779 100 36 70 0 1114541696 11652592 9069488
05:35:46 7370 8192000000 10021409392 10428882944 1096932144 11.809 100 28 57 0 1128346528 25525376 5889008
05:36:54 38217 8192000000 10032641872 10428936192 1100830448 11.846 100 45 90 0 1134145424 24123056 9191920
05:38:34 7456 8192000000 10019798880 10422415360 1096191968 11.801 100 37 74 1 1130807376 26803984 7811424
05:39:43 9779 8192000000 9999139968 10397745152 1080657136 11.654 100 37 73 0 1111653072 22784032 8211904
05:40:55 6689 8192000000 9951623968 10343038976 1014693536 11.021 97 35 68 1 1034966352 12386240 7886576
05:42:02 40894 8192000000 9927246384 10315395072 983227248 10.716 95 23 46 1 1002990288 14896000 4867040
05:43:49 6932 8192000000 9932083264 10325905408 1019245296 11.065 100 35 70 0 1072261184 45182800 7833088
05:44:56 13056 8192000000 9957218288 10355482624 1038363456 11.249 100 40 79 1 1070247088 22786912 9096720
05:46:13 7285 8192000000 9977709296 10362478592 1057004528 11.428 100 34 68 3 1082500064 17851648 7643888
05:47:21 31272 8192000000 9975600416 10384154624 1061275376 11.469 100 19 38 0 1093767744 28355584 4136784
05:49:01 25069 8192000000 10034426576 10424868864 1092276256 11.764 100 66 133 2 1141991040 35283568 14431216
05:50:29 4373 8192000000 10080789648 10477379584 1109348752 11.926 100 65 130 0 1193805568 70225712 14231104
05:51:39 36355 8192000000 10109468768 10512908288 1124172160 12.066 100 45 90 0 1210723536 77095168 9456208
05:53:17 34373 8192000000 10149823040 10560413696 1140436560 12.220 100 43 86 1 1255310112 105347648 9525904
05:55:08 10085 8192000000 10177555456 10589986816 1147222416 12.283 100 48 94 0 1292958928 135420720 10315792
05:56:22 2717 8192000000 10240379072 10641207296 1158980848 12.394 100 89 173 2 1345699600 167685712 19033040
05:57:28 1183 8192000000 10307349600 10706710528 1168605376 12.484 100 91 181 3 1405333312 216490800 20237136
05:58:34 6916 8192000000 10318870304 10733019136 1179835040 12.589 100 61 121 1 1424727984 231020992 13871952
05:59:44 6719 8192000000 10348567312 10773233664 1187622672 12.661 100 58 116 0 1453818768 252097344 14098752
06:00:58 1798 8192000000 10365441120 10779103232 1184402688 12.631 100 68 133 1 1464360592 263540480 16417424
06:02:04 2421 8192000000 10438388752 10845306880 1191868528 12.701 100 83 166 2 1537717840 327204496 18644816
06:03:16 2306 8192000000 10393228384 10828304384 1189132672 12.675 100 85 168 1 1494506608 284838960 20534976
06:04:23 2482 8192000000 10399267408 10805575680 1178813616 12.579 100 64 128 0 1490804608 296856112 15134880
06:05:31 4207 8192000000 10422219264 10834051072 1168949744 12.487 100 70 138 0 1521685040 336169840 16565456
06:06:40 3713 8192000000 10422996736 10882572288 1181660528 12.606 100 55 110 1 1520887232 326163248 13063456
06:07:58 7847 8192000000 10444854320 10863923200 1159832592 12.402 98 45 91 1 1537156352 365833216 11490544
06:09:11 15436 8192000000 10472170928 10894811136 1163136352 12.433 100 42 84 0 1569029280 394908736 10984192
06:10:30 6771 8192000000 10485989952 10929954816 1147585920 12.287 99 40 78 1 1579457056 421745056 10126080
06:11:50 21894 8192000000 10453689504 10885386240 1126357504 12.087 99 30 60 1 1547450720 412224320 8868896
06:14:13 45712 8192000000 10041177808 10553020416 1107844224 11.912 100 18 35 1 1305828736 193336000 4648512
06:16:02 6840 8192000000 10062494080 10476642304 1107495184 11.909 99 58 116 2 1158122560 38261728 12365648
06:17:10 83919 8192000000 10104251120 10584211456 1114305776 11.973 99 21 41 0 1213005056 93700384 4998896
06:19:36 115252 8192000000 10099615936 10534404096 1100177392 11.839 97 25 50 0 1141355376 36007840 5170144
06:22:35 125170 8192000000 10105186144 10546765824 1070817184 11.560 94 27 54 0 1184382672 107816608 5748880
06:25:41 31055 8192000000 10134959888 10559827968 1099730560 11.835 97 36 73 0 1193394752 85328512 8335680
06:27:28 146557 8192000000 10085133280 10533519360 1064265920 11.497 94 14 28 0 1132598144 65403488 2928736
06:30:55 36052 8192000000 10115338048 10577436672 1112641280 11.957 99 21 42 1 1213289696 95951840 4696576
06:32:39 45836 8192000000 10101656784 10544545792 1109688496 11.929 98 24 47 1 1182169520 67168000 5313024
06:34:30 32422 8192000000 10083870576 10510315520 1116183296 11.991 98 28 54 1 1148986000 26747664 6055040
06:36:08 26327 8192000000 10083199648 10534199296 1132156400 12.142 100 33 66 1 1177302400 38468944 6677056
06:37:46 83548 8192000000 10114605776 10606112768 1145799952 12.270 100 16 32 0 1213902960 64858160 3244848
06:40:15 104680 8192000000 10112655840 10655477760 1145647632 12.269 99 7 14 0 1226596288 79581216 1367440
06:43:04 83671 8192000000 10083221616 10551148544 1116429840 11.993 97 26 53 0 1159348144 37692960 5225344
06:45:51 85228 8192000000 10094152416 10536349696 1126161856 12.085 98 31 61 1 1157224416 24605744 6456816
06:48:24 59308 8192000000 10078368640 10576830464 1145733664 12.269 99 21 42 1 1230514608 80292016 4488928
06:50:26 48966 8192000000 10083311472 10551107584 1155659136 12.363 100 6 12 0 1188834640 32250672 924832
06:52:21 82225 8192000000 10108811536 10549108736 1119136640 12.019 98 16 29 2 1153720352 31082656 3501056
06:54:44 38781 8192000000 10094570448 10545143808 1127052752 12.094 98 32 64 1 1168652608 34893440 6706416
06:56:40 42988 8192000000 10101095984 10543042560 1131232704 12.133 98 33 66 1 1198402256 60244592 6924960
06:58:23 18236 8192000000 10099862304 10540531712 1143331152 12.247 99 39 78 1 1184719936 33750976 7637808
06:59:48 56343 8192000000 10125315712 10557964288 1125276112 12.077 98 25 50 1 1180427920 50247216 4904592
07:01:45 38696 8192000000 10087969408 10531295232 1113411216 11.965 98 45 90 0 1189251968 67020080 8820672
07:03:31 69409 8192000000 10104991952 10615033856 1133054288 12.150 99 31 62 0 1208510080 69081968 6373824
07:05:41 166905 8192000000 10091904928 10543833088 1078249968 11.631 95 18 36 1 1124227344 42284048 3693328
07:09:31 61409 8192000000 10080864640 10558517248 1105232128 11.887 98 42 83 3 1183882496 69977936 8672432
07:11:32 71688 8192000000 10098502304 10583638016 1124565056 12.070 99 16 32 1 1176342608 47287584 4489968
07:13:48 73705 8192000000 10079574240 10510581760 1027360880 11.143 90 27 54 1 1097955952 64643952 5951120
07:16:07 48649 8192000000 10126971392 10564739072 1099529184 11.833 98 26 52 0 1189492400 84149040 5814176
07:17:56 66591 8192000000 10149513504 10606563328 1140347472 12.219 100 27 54 1 1263910240 117816064 5746704
07:20:14 46008 8192000000 10080200576 10548838400 1110677968 11.939 98 17 34 0 1161603632 47958864 2966800
07:22:03 66440 8192000000 10096324752 10552242176 1104684928 11.882 98 21 41 1 1170429344 61759360 3985056
07:24:09 64674 8192000000 10091247872 10572664832 1102897728 11.865 97 13 26 1 1154761824 49315824 2548272
07:26:15 153512 8192000000 10082809248 10537267200 1114642384 11.976 98 30 60 0 1164231696 43657904 5931408
07:29:59 28732 8192000000 10072525456 10531545088 1138184288 12.198 100 14 28 0 1173505904 32530192 2791424
07:31:30 66618 8192000000 10099315456 10564042752 1133825472 12.157 99 14 28 1 1188340688 50975312 3539904
07:34:32 45534 8192000000 10088790528 10560327680 1125966432 12.083 98 18 36 0 1185870256 56222224 3681600
07:36:24 42514 8192000000 10137802208 10582892544 1152127392 12.329 100 26 52 1 1239781392 81946976 5707024
07:38:16 54075 8192000000 10143580816 10621214720 1122296640 12.049 98 24 48 2 1244947472 117204272 5446560
07:40:29 72190 8192000000 10120777712 10590777344 1134702160 12.166 99 17 34 0 1196454496 57926816 3825520
07:43:12 36336 8192000000 10118841536 10568941568 1146705808 12.279 100 35 70 2 1192024912 36989472 8329632
07:44:51 109899 8192000000 10083140096 10555318272 1091772720 11.760 95 15 30 2 1148323088 53280256 3270112
07:47:42 40923 8192000000 9241954848 9544294400 385247312 4.491 34 6 10 1 399993632 13544848 1201472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2021 07:51:05 to 07-12-2021 07:51:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2021 07:51:21 to 07-12-2021 07:51:23.

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          225728
MON$OLDEST_ACTIVE               225729
MON$OLDEST_SNAPSHOT             225729
MON$NEXT_TRANSACTION            225729
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                       2036960
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 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 ---
--- end of diff output ---

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

07.12.21 07:51:24. 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-07 07:51:26 No severe PSQL-related problems occured
07.12.21 07:51:27. End of report.