Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.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 15759000 kB
MemAvailable 30721516 kB
Buffers 0 kB
Cached 15974908 kB
SwapCached 3872 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-10-12 13:37:08
DTS_END                         2021-10-12 13:37:08.0840
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/40594
TRN_ID                          235059
ATT_ID                          641
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: 9275.69 25148 1113083
customer order: creation 168.14 384 20177
customer order: refuse 14.18 305 1701
order to supplier: creation 45.20 1352 5424
order to supplier: removal 3.34 110977 401
invoice (draft): creation 45.23 3079 5427
invoice (draft): removal 53.55 958 6426
invoice accept: apply 40.31 18647 4837
invoice accept: cancel 48.40 858 5808
customer reserve: creation 3786.93 181 454431
customer reserve: removal 4779.67 60 573560
realization accept: apply 82.23 196 9867
realization accept: cancel 82.61 119 9913
payment from customer: creation 57.44 123 6893
payment from customer: removal 5.48 84 658
payment to supplier: creation 48.58 149 5830
payment to supplier: removal 3.46 72 415
service: total inventory turnovers 3.70 3411 444
service: total monetary turnovers 6.43 388 771
service: aggregate perf. data 0.80 16869 96
service: refresh index statistics 0.03 344747 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2021 13:38:31 to 12-10-2021 13:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6686
TEST_TIME 2 4734
TEST_TIME 3 4606
TEST_TIME 4 4437
TEST_TIME 5 5014
TEST_TIME 6 5373
TEST_TIME 7 5821
TEST_TIME 8 5919
TEST_TIME 9 5839
TEST_TIME 10 5882
TEST_TIME 11 6621
TEST_TIME 12 6570
TEST_TIME 13 6611
TEST_TIME 14 7394
TEST_TIME 15 7687
TEST_TIME 16 7833
TEST_TIME 17 7933
TEST_TIME 18 8534
TEST_TIME 19 8465
TEST_TIME 20 8450
TEST_TIME 21 8643
TEST_TIME 22 8648
TEST_TIME 23 9473
TEST_TIME 24 10652
TEST_TIME 25 10794
TEST_TIME 26 11077
TEST_TIME 27 10694
TEST_TIME 28 10817
TEST_TIME 29 10831
TEST_TIME 30 10917
TEST_TIME 31 10776
TEST_TIME 32 10603
TEST_TIME 33 10505
TEST_TIME 34 10445
TEST_TIME 35 10470
TEST_TIME 36 10578
TEST_TIME 37 10609
TEST_TIME 38 10600
TEST_TIME 39 10628
TEST_TIME 40 10662
TEST_TIME 41 10612
TEST_TIME 42 10656
TEST_TIME 43 10630
TEST_TIME 44 10450
TEST_TIME 45 10297
TEST_TIME 46 10182
TEST_TIME 47 10129
TEST_TIME 48 10119
TEST_TIME 49 10090
TEST_TIME 50 10042
TEST_TIME 51 10018
TEST_TIME 52 9884
TEST_TIME 53 9850
TEST_TIME 54 9810
TEST_TIME 55 9743
TEST_TIME 56 9721
TEST_TIME 57 9825
TEST_TIME 58 9910
TEST_TIME 59 9891
TEST_TIME 60 9890
TEST_TIME 61 9939
TEST_TIME 62 9937
TEST_TIME 63 9975
TEST_TIME 64 9915
TEST_TIME 65 10014
TEST_TIME 66 10014
TEST_TIME 67 10039
TEST_TIME 68 9996
TEST_TIME 69 10014
TEST_TIME 70 9915
TEST_TIME 71 9816
TEST_TIME 72 9775
TEST_TIME 73 9730
TEST_TIME 74 9697
TEST_TIME 75 9669
TEST_TIME 76 9662
TEST_TIME 77 9630
TEST_TIME 78 9591
TEST_TIME 79 9571
TEST_TIME 80 9650
TEST_TIME 81 9603
TEST_TIME 82 9634
TEST_TIME 83 9620
TEST_TIME 84 9756
TEST_TIME 85 9768
TEST_TIME 86 9763
TEST_TIME 87 9775
TEST_TIME 88 9770
TEST_TIME 89 9803
TEST_TIME 90 9725
TEST_TIME 91 9678
TEST_TIME 92 9654
TEST_TIME 93 9623
TEST_TIME 94 9592
TEST_TIME 95 9624
TEST_TIME 96 9567
TEST_TIME 97 9575
TEST_TIME 98 9533
TEST_TIME 99 9503
TEST_TIME 100 9527
TEST_TIME 101 9491
TEST_TIME 102 9483
TEST_TIME 103 9583
TEST_TIME 104 9563
TEST_TIME 105 9634
TEST_TIME 106 9646
TEST_TIME 107 9650
TEST_TIME 108 9729
TEST_TIME 109 9652
TEST_TIME 110 9614
TEST_TIME 111 9571
TEST_TIME 112 9553
TEST_TIME 113 9526
TEST_TIME 114 9478
TEST_TIME 115 9476
TEST_TIME 116 9444
TEST_TIME 117 9392
TEST_TIME 118 9356
TEST_TIME 119 9351
TEST_TIME 120 9276

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2021 13:38:35 to 12-10-2021 13:38:38.

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 1533816 1533816 0 0.00 0 20531 7 0 0
doc_list_biud 1533815 1533815 0 0.00 0 35645 29 0 0
sp_add_invoice_to_stock 4950 4837 113 2.28 22 211391 18647 0 113
sp_cancel_adding_invoice 5808 5808 0 0.00 5 65526 858 0 0
sp_cancel_client_order 1701 1701 0 0.00 5 9284 306 0 0
sp_cancel_customer_reserve 573560 573560 0 0.00 1 35646 61 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 6066 84 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 4093 73 0 0
sp_cancel_supplier_invoice 6429 6426 3 0.04 2 21499 958 0 3
sp_cancel_supplier_order 401 401 0 0.00 5 949247 110978 0 0
sp_cancel_write_off 9913 9913 0 0.00 1 21200 119 0 0
sp_client_order 20177 20177 0 0.00 4 27583 384 0 0
sp_customer_reserve 454431 454431 0 0.00 0 51411 181 0 0
sp_fill_shopping_cart 31013 31013 0 0.00 1 13364 39 0 0
sp_fill_shopping_cart_clo_res 454427 454427 0 0.00 0 17786 17 0 0
sp_get_clo_for_invoice 4833 4833 0 0.00 0 34037 400 0 0
sp_kill_cost_storno 16794 16794 0 0.00 0 17814 56 0 0
sp_kill_qstorno_ret_qs2qd 1163860 1163860 0 0.00 0 35643 34 0 0
sp_kill_qty_storno 612509 612509 0 0.00 0 35645 72 0 0
sp_lock_selected_doc 20383 20383 0 0.00 0 9407 7 0 0
sp_make_cost_storno 27423 27423 0 0.00 0 19282 64 0 0
sp_multiply_rows_for_pdistr 27423 27423 0 0.00 0 10760 36 0 0
sp_multiply_rows_for_qdistr 20177 20177 0 0.00 0 11698 200 0 0
sp_pay_from_customer 6893 6893 0 0.00 4 19842 123 0 0
sp_pay_to_supplier 5830 5830 0 0.00 5 17062 149 0 0
sp_payment_common 12723 12723 0 0.00 2 11327 32 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 9276 281 0 0
sp_qd_handle_on_invoice_upd_sts 10641 10641 0 0.00 0 27618 336 0 0
sp_qd_handle_on_reserve_upd_sts 9867 9867 0 0.00 0 10514 55 0 0
sp_reserve_write_off 9867 9867 0 0.00 6 12153 197 0 0
sp_supplier_invoice 5427 5427 0 0.00 6 86322 3079 0 0
sp_supplier_order 5424 5424 0 0.00 5 42361 1353 0 0
srv_aggregate_perf_data 97 96 1 1.03 2795 142603 16869 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1805 137253 47785 0 0
srv_find_qd_qs_mism 1066269 1066269 0 0.00 0 20530 6 0 0
srv_make_invnt_saldo 444 444 0 0.00 45 92804 3412 0 0
srv_make_money_saldo 771 771 0 0.00 1 10110 388 0 0
srv_recalc_idx_stat 4 4 0 0.00 187309 488753 344748 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4837 4837 0 0.00 0 18614 170 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 5001 89 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 8663 23 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 3952 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 3902 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 11828 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 3142 15 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 40422 1660 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 6793 16 0 0
t$perf-norm:sp_client_order 20177 20177 0 0.00 0 8544 20 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 3203 10 0 0
t$perf-norm:sp_pay_from_customer 6893 6893 0 0.00 0 6764 15 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 8952 17 0 0
t$perf-norm:sp_reserve_write_off 9867 9867 0 0.00 0 8359 13 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 5788 20 0 0
t$perf-norm:sp_supplier_order 5424 5424 0 0.00 0 24304 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 722 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 993 10 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 458 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 103 42 0 0
v_add_invoice_to_stock 9892 9666 226 2.28 0 8543 17 0 226
v_all_customers 37152 37152 0 0.00 0 79 1 0 0
v_all_suppliers 30350 30350 0 0.00 0 48 1 0 0
v_all_wares 40354 40354 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 218 6 0 0
v_cancel_client_order 3402 3402 0 0.00 0 4919 5 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 5975 16 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 909 3 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 4084 11 0 0
v_cancel_supplier_invoice 882 876 6 0.68 0 1109 12 0 6
v_cancel_supplier_order 802 802 0 0.00 0 464 3 0 0
v_cancel_write_off 1924 1924 0 0.00 0 3707 15 0 0
v_max_id_clo_ord 5415 5415 0 0.00 0 420 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 2658 11 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 5985 5 0 0
v_max_non_paid_invoice 1491 1491 0 0.00 0 3652 7 0 0
v_max_non_paid_realizn 3482 3482 0 0.00 0 10257 9 0 0
v_min_id_clo_ord 5415 5415 0 0.00 0 2401 5 0 0
v_min_id_clo_res 983 983 0 0.00 0 11945 18 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 5413 6 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 9830 19 0 0
v_min_non_paid_realizn 6893 6893 0 0.00 0 10299 24 0 0
v_our_firm 10024 10024 0 0.00 0 13 1 0 0
v_reserve_write_off 19734 19734 0 0.00 0 5106 4 0 0
x_lock_depdocs_on_canc_invoice 11796 11796 0 0.00 0 16257 105 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 9109 333 0 0
x_make_qstorno_1000_1200 5424 5424 0 0.00 1 42331 1279 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 86288 2939 0 0
x_make_qstorno_2100_3300 454431 454431 0 0.00 0 51410 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2021 13:38:39 to 12-10-2021 13:38:43.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 31098 8192000000 9723763536 10021273600 802148992 8.918 100 90 179 2 831057680 7606048 21302640
11:09:49 14306 8192000000 9773395936 10080878592 840340064 9.303 100 94 189 0 874559600 11970272 22249264
11:11:09 21516 8192000000 9816257664 10134028288 877425584 9.674 100 87 174 1 918091152 20983040 19682528
11:12:37 9217 8192000000 9880765808 10213167104 914184512 10.039 100 83 165 2 977234720 45111888 17938320
11:13:52 6968 8192000000 9932423264 10277269504 960547360 10.494 100 78 156 1 1043335168 66316992 16470816
11:15:01 3959 8192000000 9965981824 10321104896 1002034496 10.898 100 81 162 1 1076899232 58096112 16768624
11:16:06 2244 8192000000 10001851648 10361765888 1019771792 11.070 100 77 155 0 1112495424 77636176 15087456
11:17:11 1545 8192000000 9992872400 10360233984 1048127728 11.343 100 80 160 1 1115442032 50196144 17118160
11:18:15 1613 8192000000 10044573904 10415087616 1065252160 11.507 100 90 178 1 1153817216 70641712 17923344
11:19:18 23640 8192000000 10004884528 10387279872 1075250688 11.602 100 80 161 1 1120007248 28041952 16714608
11:20:42 3889 8192000000 9992277888 10385625088 1079522640 11.643 100 49 98 0 1108361888 18298576 10540672
11:21:50 5070 8192000000 9991283008 10388234240 1094837824 11.789 100 51 102 0 1124441648 18688624 10915200
11:22:56 7553 8192000000 10004054592 10404745216 1093108880 11.772 100 32 64 0 1118584896 18448352 7027664
11:24:10 20135 8192000000 10033977200 10420408320 1101641600 11.853 100 88 176 2 1139760128 18119664 19998864
11:25:30 10514 8192000000 10055498720 10441490432 1106857440 11.903 100 73 148 0 1159926960 36841440 16228080
11:26:41 5290 8192000000 10010917488 10413715456 1086943328 11.714 98 32 64 1 1104465424 10553120 6968976
11:27:48 7212 8192000000 9987525008 10400845824 1069934016 11.551 99 23 46 1 1107290256 32630096 4726144
11:28:55 41908 8192000000 9958069600 10331492352 1024233008 11.113 96 47 96 1 1050987040 16939648 9814384
11:30:40 5360 8192000000 9960960816 10341793792 1025000736 11.120 97 53 108 1 1052523328 15466016 12056576
11:31:47 10301 8192000000 9947810816 10321887232 1029183360 11.161 100 39 78 0 1056994960 18661792 9149808
11:33:02 8166 8192000000 9966774960 10362187776 1048046176 11.342 100 44 88 0 1077000240 19520768 9433296
11:34:11 34245 8192000000 9961181504 10367950848 1050599088 11.366 100 20 40 2 1079075536 24065232 4411216
11:35:46 4678 8192000000 9999338672 10380378112 1064127456 11.496 100 47 91 3 1110332368 36586288 9618624
11:36:51 8329 8192000000 9988137280 10397982720 1071098512 11.563 100 51 100 3 1098869216 16583200 11187504
11:38:05 11408 8192000000 10038047056 10435682304 1104319488 11.879 100 44 88 3 1151643936 38098176 9226272
11:39:22 101721 8192000000 10087377360 10505785344 1119284656 12.020 100 40 80 1 1206325952 78460016 8581280
11:42:06 4934 8192000000 10141663024 10554929152 1141021536 12.225 100 57 112 2 1253602688 99703616 12877536
11:43:18 9416 8192000000 10157962464 10568192000 1144450016 12.257 100 51 102 0 1261791232 106154192 11187024
11:44:31 6593 8192000000 10172940304 10597990400 1155014048 12.357 100 44 88 1 1279764656 115363872 9386736
11:45:40 5824 8192000000 10230335088 10637864960 1168313120 12.481 100 64 121 1 1328378224 146999088 13066016
11:46:57 9253 8192000000 10239055056 10655121408 1174918864 12.543 100 56 112 0 1351439392 163601904 12918624
11:48:13 4408 8192000000 10258999264 10667634688 1163857648 12.439 99 60 119 2 1359073056 181830768 13384640
11:49:23 5330 8192000000 10320201328 10724909056 1173841456 12.533 100 73 146 1 1418064272 228056528 16166288
11:50:32 6553 8192000000 10354893104 10763845632 1173460560 12.529 100 69 138 0 1455593376 266038048 16094768
11:51:47 1805 8192000000 10358029424 10782322688 1190157264 12.685 100 77 151 2 1457418528 249610544 17650720
11:52:54 2041 8192000000 10374814128 10777829376 1182113760 12.610 100 87 173 1 1476968800 274597216 20257824
11:54:00 2559 8192000000 10406010896 10810044416 1180910288 12.599 100 80 159 0 1498160224 298680368 18569568
11:55:08 7206 8192000000 10374791824 10846838784 1185166336 12.638 100 43 86 0 1487382736 291391680 10824720
11:56:17 14506 8192000000 10376345744 10816053248 1172349984 12.519 100 30 60 0 1487167936 307171520 7646432
11:57:52 21897 8192000000 10406076048 10886242304 1158515168 12.389 100 26 52 0 1527262432 361563440 7183824
11:59:15 31356 8192000000 10440303680 10888372224 1055660320 11.415 94 27 54 0 1473774304 410149568 7964416
12:00:53 28113 8192000000 10257922736 10713157632 1090626016 11.749 98 23 46 0 1387594704 290715152 6253536
12:02:23 117239 8192000000 10159238192 10629464064 1058593056 11.443 95 11 22 0 1197646992 136459568 2594368
12:05:56 49776 8192000000 10078447280 10565283840 1107328912 11.907 100 19 38 1 1203547536 92302112 3916512
12:07:50 58848 8192000000 10052428128 10473512960 1081032160 11.657 97 23 48 4 1122817568 37345712 4439696
12:09:52 137252 8192000000 10128675056 10588033024 1122695616 12.052 99 17 34 0 1175677648 49838656 3143376
12:13:10 87634 8192000000 10109605248 10562183168 1098106304 11.820 97 12 24 0 1155823248 56000320 1716624
12:15:39 94911 8192000000 10120128896 10545745920 1104849664 11.884 97 11 22 0 1163967264 56614400 2503200
12:18:18 45177 8192000000 10081495648 10513354752 1121385984 12.040 98 33 66 1 1151194672 22972192 6836496
12:20:05 131218 8192000000 10074180144 10511695872 1125661088 12.080 99 18 36 0 1156788512 27433552 3693872
12:23:25 68294 8192000000 10071867200 10537140224 1126987472 12.093 99 26 52 0 1180180368 47496848 5696048
12:25:36 34066 8192000000 10087189632 10520248320 1145417120 12.266 100 35 69 0 1198977136 45873040 7686976
12:27:47 44213 8192000000 10111533376 10561441792 1130943232 12.130 98 20 40 0 1177357184 42119904 4294048
12:29:32 64287 8192000000 10144645232 10595672064 1145114880 12.264 99 21 42 2 1234950672 84877136 4958656
12:31:44 51557 8192000000 10159303200 10606338048 1149003936 12.300 100 24 48 1 1251061744 96378784 5679024
12:33:42 67360 8192000000 10132704000 10645848064 1140994288 12.225 99 20 40 1 1264358528 119153712 4210528
12:37:03 39573 8192000000 10085472832 10524172288 1127753408 12.100 98 34 66 2 1169293840 35292256 6248176
12:38:46 62257 8192000000 10115120768 10564288512 1130862304 12.129 98 20 38 1 1168544848 33972528 3710016
12:40:54 2241 8192000000 10085825920 10507526144 1150020096 12.310 100 70 139 2 1177879792 13604656 14255040
12:41:57 45536 8192000000 10123912176 10619256832 1138860768 12.205 99 32 64 3 1214307792 68951280 6495744
12:43:43 71785 8192000000 10058583072 10494775296 1082265856 11.669 94 11 22 0 1108760576 24376304 2118416
12:45:57 50078 8192000000 10065573488 10497388544 1092137872 11.763 97 29 58 0 1132731552 35269872 5323808
12:47:51 66074 8192000000 10082361200 10513235968 1066060512 11.514 95 27 54 0 1131110832 59029824 6020496
12:50:32 52674 8192000000 10064685152 10559066112 1085742160 11.702 98 16 32 0 1193520256 104056288 3721808
12:52:26 74967 8192000000 10075297760 10562551808 1116259920 11.992 100 17 34 1 1197008656 76753680 3995056
12:54:41 56724 8192000000 10079962928 10521616384 1092261760 11.764 97 14 28 0 1160451168 65250672 2938736
12:56:39 31447 8192000000 10103925440 10532347904 1076028992 11.610 97 21 41 1 1168550880 88196608 4325280
12:58:36 41214 8192000000 10144201600 10571460608 1093167920 11.773 97 28 57 0 1184452480 85086400 6198160
13:00:19 56071 8192000000 10136774720 10599772160 1120384704 12.031 100 15 30 1 1231289664 107407040 3497920
13:02:15 32719 8192000000 10092053376 10530791424 1096954672 11.809 98 43 86 2 1173211120 67649952 8606496
13:03:49 62806 8192000000 10062570144 10503327744 1074660704 11.597 96 33 66 0 1122654704 41642224 6351776
13:05:54 73116 8192000000 10047666368 10489151488 1074521248 11.595 96 22 45 0 1144703008 65102720 5079040
13:08:09 65425 8192000000 10049814976 10505408512 1080321264 11.651 96 20 40 1 1122849536 38757488 3770784
13:10:19 49912 8192000000 10121441424 10567806976 1137543072 12.192 100 40 80 2 1199569872 53918544 8108256
13:12:12 39630 8192000000 10070777536 10581999616 1135772192 12.176 100 10 20 0 1219725456 81729024 2224240
13:13:58 48937 8192000000 10046044848 10513879040 1112292432 11.954 98 15 30 1 1143809664 28446608 3070624
13:15:49 58601 8192000000 10138611232 10594107392 1132445264 12.144 99 31 60 2 1205880704 66405552 7029888
13:17:48 66459 8192000000 10170691584 10620846080 1141901600 12.233 99 16 32 0 1242387504 96831984 3653920
13:20:05 64731 8192000000 10128862432 10587971584 1111048112 11.942 97 14 28 1 1215196832 100928496 3220224
13:22:46 31394 8192000000 10121018896 10554384384 1137495168 12.192 100 42 84 1 1210309168 63892976 8921024
13:24:21 29122 8192000000 10168059488 10620166144 1145208576 12.264 100 44 88 0 1239816640 85707280 8900784
13:27:06 94681 8192000000 10080895456 10536849408 1144988592 12.262 100 19 36 0 1188584032 40084720 3510720
13:29:49 75823 8192000000 10065092064 10538319872 1114465664 11.975 97 9 18 1 1159962032 43590992 1905376
13:32:46 60027 8192000000 10055188816 10513633280 1108070848 11.914 97 12 23 1 1142652144 32215568 2365728
13:34:53 84006 8192000000 10089219008 10562400256 1135820800 12.176 100 19 38 0 1201027088 61314144 3892144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-10-2021 13:38:45 to 12-10-2021 13:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 113 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2021 13:39:01 to 12-10-2021 13:39:03.

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          234882
MON$OLDEST_ACTIVE               235389
MON$OLDEST_SNAPSHOT             235389
MON$NEXT_TRANSACTION            235389
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                       2060768
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2628 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2628 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2628 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2628 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Tue Oct 12 13:37:10 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Oct 12 13:37:10 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

12.10.21 13:39:04. 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-10-12 13:39:06 No severe PSQL-related problems occured
12.10.21 13:39:07. End of report.