Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2021 13: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15738156 kB
MemAvailable 30870636 kB
Buffers 0 kB
Cached 15998420 kB
SwapCached 2480 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-11-03 13:37:08
DTS_END                         2021-11-03 13:37:08.8790
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/46386
TRN_ID                          225528
ATT_ID                          642
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: 9342.26 22457 1121071
customer order: creation 164.98 455 19798
customer order: refuse 13.65 387 1638
order to supplier: creation 43.88 1519 5265
order to supplier: removal 3.38 144078 405
invoice (draft): creation 44.12 4159 5294
invoice (draft): removal 53.76 1088 6451
invoice accept: apply 40.15 20387 4818
invoice accept: cancel 48.96 918 5875
customer reserve: creation 3795.84 194 455501
customer reserve: removal 4841.23 78 580947
realization accept: apply 80.58 220 9670
realization accept: cancel 89.88 132 10786
payment from customer: creation 56.59 127 6791
payment from customer: removal 5.47 116 656
payment to supplier: creation 46.88 152 5626
payment to supplier: removal 3.16 84 379
service: total inventory turnovers 3.38 4803 406
service: total monetary turnovers 5.68 720 681
service: aggregate perf. data 0.67 23397 80
service: refresh index statistics 0.03 246119 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2021 13:39:13 to 03-11-2021 13:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5421
TEST_TIME 2 5311
TEST_TIME 3 5628
TEST_TIME 4 4865
TEST_TIME 5 4975
TEST_TIME 6 5075
TEST_TIME 7 4999
TEST_TIME 8 5139
TEST_TIME 9 5560
TEST_TIME 10 5644
TEST_TIME 11 6269
TEST_TIME 12 6590
TEST_TIME 13 7114
TEST_TIME 14 7497
TEST_TIME 15 7832
TEST_TIME 16 8110
TEST_TIME 17 8201
TEST_TIME 18 8317
TEST_TIME 19 8512
TEST_TIME 20 8533
TEST_TIME 21 8895
TEST_TIME 22 9029
TEST_TIME 23 8872
TEST_TIME 24 8922
TEST_TIME 25 8811
TEST_TIME 26 8840
TEST_TIME 27 9309
TEST_TIME 28 9728
TEST_TIME 29 10070
TEST_TIME 30 10969
TEST_TIME 31 11572
TEST_TIME 32 11453
TEST_TIME 33 11380
TEST_TIME 34 11449
TEST_TIME 35 11503
TEST_TIME 36 11345
TEST_TIME 37 11157
TEST_TIME 38 11029
TEST_TIME 39 10862
TEST_TIME 40 10658
TEST_TIME 41 10605
TEST_TIME 42 10599
TEST_TIME 43 10399
TEST_TIME 44 10365
TEST_TIME 45 10297
TEST_TIME 46 10232
TEST_TIME 47 10074
TEST_TIME 48 10101
TEST_TIME 49 10002
TEST_TIME 50 10174
TEST_TIME 51 10129
TEST_TIME 52 10134
TEST_TIME 53 9990
TEST_TIME 54 10028
TEST_TIME 55 9910
TEST_TIME 56 9825
TEST_TIME 57 9708
TEST_TIME 58 9666
TEST_TIME 59 9622
TEST_TIME 60 9575
TEST_TIME 61 9685
TEST_TIME 62 9688
TEST_TIME 63 9703
TEST_TIME 64 9713
TEST_TIME 65 9740
TEST_TIME 66 9919
TEST_TIME 67 9886
TEST_TIME 68 9876
TEST_TIME 69 9930
TEST_TIME 70 9913
TEST_TIME 71 9939
TEST_TIME 72 9942
TEST_TIME 73 9904
TEST_TIME 74 9804
TEST_TIME 75 9747
TEST_TIME 76 9757
TEST_TIME 77 9692
TEST_TIME 78 9690
TEST_TIME 79 9626
TEST_TIME 80 9594
TEST_TIME 81 9525
TEST_TIME 82 9574
TEST_TIME 83 9607
TEST_TIME 84 9639
TEST_TIME 85 9635
TEST_TIME 86 9646
TEST_TIME 87 9677
TEST_TIME 88 9641
TEST_TIME 89 9691
TEST_TIME 90 9668
TEST_TIME 91 9685
TEST_TIME 92 9747
TEST_TIME 93 9704
TEST_TIME 94 9707
TEST_TIME 95 9650
TEST_TIME 96 9617
TEST_TIME 97 9578
TEST_TIME 98 9516
TEST_TIME 99 9513
TEST_TIME 100 9511
TEST_TIME 101 9522
TEST_TIME 102 9483
TEST_TIME 103 9466
TEST_TIME 104 9476
TEST_TIME 105 9448
TEST_TIME 106 9467
TEST_TIME 107 9519
TEST_TIME 108 9466
TEST_TIME 109 9530
TEST_TIME 110 9583
TEST_TIME 111 9600
TEST_TIME 112 9616
TEST_TIME 113 9539
TEST_TIME 114 9498
TEST_TIME 115 9431
TEST_TIME 116 9415
TEST_TIME 117 9371
TEST_TIME 118 9365
TEST_TIME 119 9398
TEST_TIME 120 9346

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2021 13:39:17 to 03-11-2021 13:39:20.

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 1542473 1542473 0 0.00 0 22279 8 0 0
doc_list_biud 1542473 1542473 0 0.00 0 31655 37 0 0
sp_add_invoice_to_stock 4928 4818 110 2.23 30 196524 20387 0 110
sp_cancel_adding_invoice 5875 5875 0 0.00 5 102746 919 0 0
sp_cancel_client_order 1638 1638 0 0.00 5 7774 388 0 0
sp_cancel_customer_reserve 580947 580947 0 0.00 1 30543 78 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 2 7768 116 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 2615 85 0 0
sp_cancel_supplier_invoice 6461 6451 10 0.15 2 31634 1089 0 10
sp_cancel_supplier_order 405 405 0 0.00 11 1503235 144079 0 0
sp_cancel_write_off 10786 10786 0 0.00 1 9290 132 0 0
sp_client_order 19798 19798 0 0.00 4 28773 456 0 0
sp_customer_reserve 455501 455501 0 0.00 0 59882 195 0 0
sp_fill_shopping_cart 30356 30356 0 0.00 1 17493 48 0 0
sp_fill_shopping_cart_clo_res 455498 455498 0 0.00 0 16396 17 0 0
sp_get_clo_for_invoice 4815 4815 0 0.00 0 26213 354 0 0
sp_kill_cost_storno 17696 17696 0 0.00 0 22271 72 0 0
sp_kill_qstorno_ret_qs2qd 1179536 1179536 0 0.00 0 31618 42 0 0
sp_kill_qty_storno 620587 620587 0 0.00 0 31654 90 0 0
sp_lock_selected_doc 19933 19933 0 0.00 0 9825 7 0 0
sp_make_cost_storno 26902 26902 0 0.00 0 16000 69 0 0
sp_multiply_rows_for_pdistr 26902 26902 0 0.00 0 6586 36 0 0
sp_multiply_rows_for_qdistr 19798 19798 0 0.00 0 19407 223 0 0
sp_pay_from_customer 6791 6791 0 0.00 4 11670 127 0 0
sp_pay_to_supplier 5626 5626 0 0.00 5 16130 153 0 0
sp_payment_common 12417 12417 0 0.00 1 9399 23 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 7756 354 0 0
sp_qd_handle_on_invoice_upd_sts 10690 10690 0 0.00 0 31653 421 0 0
sp_qd_handle_on_reserve_upd_sts 9670 9670 0 0.00 0 19223 69 0 0
sp_reserve_write_off 9670 9670 0 0.00 7 19251 220 0 0
sp_supplier_invoice 5294 5294 0 0.00 6 118593 4159 0 0
sp_supplier_order 5265 5265 0 0.00 5 34977 1519 0 0
srv_aggregate_perf_data 80 80 0 0.00 3144 257935 23398 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 877 112785 37684 0 0
srv_find_qd_qs_mism 1074805 1074805 0 0.00 0 12948 6 0 0
srv_make_invnt_saldo 406 406 0 0.00 93 122149 4803 0 0
srv_make_money_saldo 681 681 0 0.00 1 19757 720 0 0
srv_recalc_idx_stat 4 4 0 0.00 156133 363785 246119 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 27587 214 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 9279 97 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 12557 24 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1919 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 2367 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 16226 46 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 5867 34 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 25623 1914 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 3503 12 0 0
t$perf-norm:sp_client_order 19798 19798 0 0.00 0 13613 25 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 1705 9 0 0
t$perf-norm:sp_pay_from_customer 6791 6791 0 0.00 0 10577 13 0 0
t$perf-norm:sp_pay_to_supplier 5626 5626 0 0.00 0 8457 13 0 0
t$perf-norm:sp_reserve_write_off 9670 9670 0 0.00 0 18535 18 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 18175 24 0 0
t$perf-norm:sp_supplier_order 5265 5265 0 0.00 0 6783 20 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 159 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 273 7 0 0
t$perf-norm:srv_make_invnt_saldo 406 406 0 0.00 0 1155 17 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 3466 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9846 9626 220 2.23 0 9362 20 0 220
v_all_customers 36414 36414 0 0.00 0 14 1 0 0
v_all_suppliers 29684 29684 0 0.00 0 18 1 0 0
v_all_wares 39596 39596 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 164 6 0 0
v_cancel_client_order 3276 3276 0 0.00 0 3580 6 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 7614 18 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3391 10 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2567 13 0 0
v_cancel_supplier_invoice 766 746 20 2.61 0 958 14 0 20
v_cancel_supplier_order 810 810 0 0.00 0 2614 7 0 0
v_cancel_write_off 1902 1902 0 0.00 0 2790 16 0 0
v_max_id_clo_ord 5265 5265 0 0.00 0 537 1 0 0
v_max_id_clo_res 998 998 0 0.00 0 4840 23 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 7255 8 0 0
v_max_non_paid_invoice 1342 1342 0 0.00 0 84 2 0 0
v_max_non_paid_realizn 3394 3394 0 0.00 0 1552 3 0 0
v_min_id_clo_ord 5265 5265 0 0.00 0 1923 5 0 0
v_min_id_clo_res 998 998 0 0.00 0 8875 24 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 9262 12 0 0
v_min_non_paid_invoice 5626 5626 0 0.00 0 7487 15 0 0
v_min_non_paid_realizn 6791 6791 0 0.00 0 6324 13 0 0
v_our_firm 9976 9976 0 0.00 0 19 1 0 0
v_reserve_write_off 19340 19340 0 0.00 0 9417 7 0 0
x_lock_depdocs_on_canc_invoice 11953 11953 0 0.00 0 11207 120 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 13061 405 0 0
x_make_qstorno_1000_1200 5265 5265 0 0.00 2 34553 1424 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 3 106108 3985 0 0
x_make_qstorno_2100_3300 455501 455501 0 0.00 0 59881 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2021 13:39:21 to 03-11-2021 13:39:25.

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
11:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 32127 8192000000 9738304848 10036432896 809536352 8.993 100 91 182 0 838614320 7781504 21296464
11:09:55 17691 8192000000 9763440688 10073923584 844203696 9.342 100 85 170 0 877336896 13445552 19687648
11:11:18 22122 8192000000 9818250656 10140983296 881605440 9.716 100 80 160 0 920472112 21240048 17626624
11:12:49 12719 8192000000 9856145600 10190716928 911251776 10.010 100 78 156 0 965633552 36996560 17385216
11:14:04 12499 8192000000 9911944192 10252836864 942712208 10.320 100 82 163 0 1026839344 66829760 17297376
11:15:20 7640 8192000000 9967529328 10316353536 983732416 10.721 100 86 171 0 1074555008 73145920 17676672
11:16:32 3844 8192000000 9975489248 10334892032 1015083248 11.025 100 86 170 1 1099242416 67648224 16510944
11:17:39 2863 8192000000 10013473664 10379366400 1046595680 11.328 100 88 175 0 1123067120 58428688 18042752
11:18:44 1607 8192000000 10030894432 10409091072 1066625760 11.520 100 74 148 0 1145229264 63305760 15297744
11:19:47 4106 8192000000 9985174480 10379591680 1070703136 11.559 100 44 87 2 1105337872 25172576 9462160
11:20:55 7807 8192000000 10004333744 10398158848 1078802592 11.636 100 37 73 0 1110542144 23775104 7964448
11:22:03 5725 8192000000 10002637504 10406031360 1085833952 11.703 100 26 54 2 1119823056 28460176 5528928
11:23:10 73659 8192000000 10014425232 10409889792 1092991824 11.771 100 41 80 3 1119300496 17505520 8803152
11:25:24 7526 8192000000 10020228864 10430779392 1104784688 11.883 100 36 69 1 1134103840 22111872 7207280
11:26:34 5993 8192000000 10023269696 10418765824 1101131312 11.848 100 40 80 1 1139721408 30208304 8381792
11:27:41 12194 8192000000 10024456064 10445185024 1091834304 11.760 99 22 44 0 1129845888 33802288 4209296
11:28:55 10587 8192000000 9993822000 10403721216 1073392496 11.584 99 32 64 0 1104570240 23662960 7514784
11:30:07 4018 8192000000 9950465168 10333532160 1001721184 10.895 95 45 90 2 1028969440 17063376 10184880
11:31:12 56755 8192000000 9938236240 10325815296 975809792 10.643 95 36 72 0 1007879776 24032448 8037536
11:33:12 13832 8192000000 9941196560 10328285184 1018213632 11.055 98 34 68 0 1050343488 25795232 6334624
11:34:27 10118 8192000000 9966134992 10364936192 1035206496 11.219 99 43 86 2 1075790944 30811696 9772752
11:35:38 5875 8192000000 9964444640 10355146752 1051861648 11.379 100 39 78 2 1077715568 17438816 8415104
11:36:44 35001 8192000000 9990204304 10387722240 1069838208 11.551 100 26 52 1 1097381392 22148256 5394928
11:38:31 23463 8192000000 10056755072 10453209088 1098588432 11.824 100 59 114 3 1159699696 49085216 12026048
11:39:59 13571 8192000000 10085717616 10493693952 1118861568 12.016 100 50 101 1 1206393104 76400960 11130576
11:41:19 8529 8192000000 10114483040 10517803008 1131364976 12.134 100 56 113 0 1220374992 76460064 12549952
11:42:35 4749 8192000000 10181130512 10587230208 1142874208 12.243 100 56 112 0 1286384752 130384464 13126080
11:43:42 24153 8192000000 10206840784 10622222336 1151803248 12.326 100 44 89 0 1313706016 151635808 10266960
11:45:22 10186 8192000000 10258786032 10677702656 1162544352 12.427 100 58 115 1 1366017728 190817648 12655728
11:46:43 15666 8192000000 10299161056 10718900224 1167543008 12.474 100 54 107 0 1413306064 232935776 12827280
11:48:12 2478 8192000000 10328985104 10749988864 1183111088 12.619 100 67 134 1 1429014224 231000704 14902432
11:49:21 4265 8192000000 10340987792 10755076096 1180034480 12.591 100 69 136 0 1438222608 242834832 15353296
11:50:32 2330 8192000000 10385986432 10806476800 1183828352 12.626 100 78 152 1 1486608880 284850384 17930144
11:51:38 6523 8192000000 10337626784 10765860864 1182514032 12.614 100 69 139 0 1444890144 245018576 17357536
11:52:49 875 8192000000 10326551888 10745909248 1176604272 12.559 100 94 187 1 1430261424 233356912 20300240
11:53:53 3327 8192000000 10348277120 10758864896 1170972096 12.506 100 73 144 1 1452528832 264577888 16978848
11:55:02 3302 8192000000 10383402320 10783649792 1178235168 12.574 100 89 177 1 1477199744 278987360 19977216
11:56:09 8151 8192000000 10339182320 10760556544 1165381136 12.454 99 55 109 0 1432732880 254225984 13125760
11:57:25 5308 8192000000 10384499584 10796703744 1151018352 12.319 100 68 134 0 1471812592 304552768 16241472
11:58:36 6043 8192000000 10377143008 10842046464 1143951184 12.253 99 41 82 2 1468456480 313841792 10663504
11:59:51 4384 8192000000 10402775440 10819379200 1159718224 12.401 100 41 81 2 1500888944 330682832 10487888
12:01:07 1336 8192000000 10477905584 10879377408 1155099616 12.357 100 82 161 4 1578213232 404049696 19063920
12:02:14 11367 8192000000 10537475696 10959273984 1153874912 12.346 100 49 98 1 1640882112 474034336 12972864
12:03:30 3922 8192000000 10561702448 10988572672 1137775792 12.195 100 65 131 1 1654255872 498361952 18118128
12:04:48 15196 8192000000 10553506448 11054047232 1152274816 12.331 100 38 76 0 1661272096 498521072 10476208
12:06:41 27224 8192000000 10353763376 10853978112 1128158176 12.104 100 31 61 2 1508431328 372625984 7647168
12:08:19 36236 8192000000 10086218032 10544689152 1100924032 11.846 98 10 20 0 1161195664 58509472 1762160
12:09:56 35801 8192000000 10076423744 10514198528 1104669472 11.882 98 13 26 1 1146945024 39999952 2275600
12:11:36 92027 8192000000 10059602640 10540752896 1102839264 11.865 98 8 16 1 1177322128 73319472 1163392
12:14:20 52872 8192000000 10087806176 10514423808 1100981888 11.847 98 34 67 0 1154626672 47055024 6589760
12:16:22 63447 8192000000 10140387200 10578055168 1126738032 12.091 100 51 102 0 1226596736 89501936 10356768
12:19:02 38346 8192000000 10040682560 10484940800 1123987776 12.065 100 16 32 0 1151888288 24686336 3214176
12:20:43 8514 8192000000 10054473424 10484596736 1123883056 12.064 100 44 88 0 1155065472 21504912 9677504
12:21:57 36660 8192000000 10127669232 10562670592 1129973424 12.121 99 34 65 1 1210887888 74317088 6597376
12:23:36 44999 8192000000 10160610512 10606469120 1135717536 12.175 99 47 92 3 1252682512 106245248 10719728
12:25:25 46474 8192000000 10185193856 10645536768 1132809680 12.148 99 19 38 0 1257349056 120500928 4038448
12:27:19 52401 8192000000 10131849936 10636988416 1126707520 12.090 99 16 31 0 1248511424 118330608 3473296
12:29:12 65312 8192000000 10089762544 10547404800 1115947776 11.989 98 16 32 0 1180328224 59914096 4466352
12:31:41 75648 8192000000 10051963984 10508079104 1102930192 11.865 97 22 42 1 1123251664 16028784 4292688
12:34:06 80688 8192000000 10077165088 10558373888 1137560320 12.193 100 19 38 0 1189551504 47868432 4122752
12:37:29 46447 8192000000 10122681584 10593697792 1129999408 12.121 99 20 39 0 1219692704 85736080 3957216
12:39:17 50711 8192000000 10102469968 10547425280 1123834608 12.063 98 13 26 0 1172214208 45363632 3015968
12:41:08 26365 8192000000 10149725728 10592858112 1141864848 12.233 99 23 46 0 1225849552 78957200 5027504
12:42:34 44989 8192000000 10169083008 10618945536 1132390320 12.144 98 13 26 2 1221413296 86134240 2888736
12:44:19 42426 8192000000 10083546192 10513719296 1128989616 12.112 99 36 73 0 1152819456 15872464 7957376
12:46:02 41339 8192000000 10092702560 10569551872 1106658464 11.901 97 33 64 1 1181591360 68038640 6894256
12:47:47 52363 8192000000 10084146784 10523922432 1073101024 11.582 95 19 38 1 1106921936 29470624 4350288
12:49:50 25107 8192000000 10051870720 10482393088 1069959872 11.552 96 24 48 1 1116661296 41946368 4755056
12:51:21 50277 8192000000 10046575344 10483109888 1090939440 11.752 98 25 50 0 1133541472 37571168 5030864
12:53:17 37459 8192000000 10071494560 10539884544 1100424864 11.842 98 21 42 0 1159024784 54293632 4306288
12:54:56 38397 8192000000 10067398336 10556080128 1112834128 11.959 100 15 30 0 1178994464 62748544 3411792
12:56:48 57044 8192000000 10083617040 10548731904 1097835424 11.817 98 14 28 1 1177160720 76170624 3154672
12:58:54 70631 8192000000 10103096192 10548060160 1127416816 12.097 100 14 28 1 1196690976 66256144 3018016
13:01:05 43635 8192000000 10062276192 10507677696 1107042336 11.904 98 14 28 1 1151729552 42030960 2656256
13:02:49 49147 8192000000 10084180160 10519351296 1072864224 11.579 95 32 64 0 1140199184 60661616 6673344
13:04:39 29005 8192000000 10057703088 10481344512 1099775664 11.836 99 36 72 1 1129576112 22566096 7234352
13:06:09 19051 8192000000 10099265504 10536013824 1080590512 11.653 97 46 92 0 1148744976 59101520 9052944
13:07:30 19530 8192000000 10094825472 10516066304 1114840128 11.978 100 47 96 2 1162337904 37421200 10076576
13:08:54 85010 8192000000 10090914384 10592849920 1096803840 11.807 97 14 28 0 1170714320 70923680 2986800
13:12:19 32790 8192000000 10064587904 10535055360 1128757040 12.110 100 19 38 0 1167384592 34884320 3743232
13:13:52 45142 8192000000 10118384784 10579177472 1125848624 12.082 99 11 22 0 1214558112 86399200 2310288
13:15:40 66665 8192000000 10095958672 10585378816 1124270288 12.067 100 13 26 1 1221574944 94345200 2959456
13:18:29 106733 8192000000 10082122512 10565120000 1139034880 12.206 100 9 18 0 1188388784 47109744 2244160
13:21:32 77921 8192000000 10121320112 10576683008 1115010048 11.980 97 21 42 0 1181455824 62321232 4124544
13:23:59 55493 8192000000 10128398112 10630680576 1116122576 11.990 97 16 32 0 1209530864 89969088 3439200
13:26:03 38542 8192000000 10181639312 10643693568 1149634256 12.306 100 40 79 3 1271398912 113496272 8268384
13:27:45 96942 8192000000 10106464320 10598572032 1119164080 12.019 98 14 28 0 1188072848 66142704 2766064
13:30:22 112785 8192000000 10101530512 10554953728 1127287392 12.096 99 28 56 2 1159211344 25880144 6043808
13:33:21 86049 8192000000 10150876976 10635968512 1141070160 12.226 99 14 28 0 1260925152 116533856 3321136
13:36:30 43653 8192000000 9186570464 9531478016 303223536 3.569 27 6 11 0 330019456 25575936 1219984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2021 13:39:27 to 03-11-2021 13:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2021 13:39:43 to 03-11-2021 13:39:45.

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          225412
MON$OLDEST_ACTIVE               225842
MON$OLDEST_SNAPSHOT             225842
MON$NEXT_TRANSACTION            225842
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                       2074400
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

03.11.21 13:39:45. 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-11-03 13:39:48 No severe PSQL-related problems occured
03.11.21 13:39:49. End of report.