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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16341884 kB
MemAvailable 31585052 kB
Buffers 0 kB
Cached 15357260 kB
SwapCached 2204 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.2
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 2022-04-02 07:37:13
DTS_END                         2022-04-02 07:37:13.2860
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/32800
TRN_ID                          214422
ATT_ID                          649
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: 8545.76 26152 1025491
customer order: creation 159.98 505 19197
customer order: refuse 13.41 401 1609
order to supplier: creation 41.66 1398 4999
order to supplier: removal 3.25 168123 390
invoice (draft): creation 43.42 3685 5210
invoice (draft): removal 49.59 989 5951
invoice accept: apply 39.51 26742 4741
invoice accept: cancel 44.25 1201 5310
customer reserve: creation 3617.35 256 434082
customer reserve: removal 4247.48 101 509697
realization accept: apply 77.71 284 9325
realization accept: cancel 84.61 159 10153
payment from customer: creation 56.51 200 6781
payment from customer: removal 5.65 114 678
payment to supplier: creation 46.12 220 5534
payment to supplier: removal 3.28 113 393
service: total inventory turnovers 3.60 4372 432
service: total monetary turnovers 7.41 412 889
service: aggregate perf. data 0.96 17944 115
service: refresh index statistics 0.04 295814 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-04-2022 07:38:35 to 02-04-2022 07:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7948
TEST_TIME 2 6080
TEST_TIME 3 6136
TEST_TIME 4 5846
TEST_TIME 5 5686
TEST_TIME 6 5605
TEST_TIME 7 5968
TEST_TIME 8 6497
TEST_TIME 9 6590
TEST_TIME 10 6749
TEST_TIME 11 7004
TEST_TIME 12 7338
TEST_TIME 13 7295
TEST_TIME 14 7534
TEST_TIME 15 7761
TEST_TIME 16 8035
TEST_TIME 17 7959
TEST_TIME 18 7945
TEST_TIME 19 7964
TEST_TIME 20 8163
TEST_TIME 21 8122
TEST_TIME 22 8361
TEST_TIME 23 8626
TEST_TIME 24 8954
TEST_TIME 25 9065
TEST_TIME 26 9314
TEST_TIME 27 9341
TEST_TIME 28 9524
TEST_TIME 29 9669
TEST_TIME 30 9487
TEST_TIME 31 10167
TEST_TIME 32 10821
TEST_TIME 33 10967
TEST_TIME 34 11054
TEST_TIME 35 10919
TEST_TIME 36 10708
TEST_TIME 37 10665
TEST_TIME 38 10504
TEST_TIME 39 10588
TEST_TIME 40 10628
TEST_TIME 41 10482
TEST_TIME 42 10325
TEST_TIME 43 10170
TEST_TIME 44 10099
TEST_TIME 45 9931
TEST_TIME 46 9906
TEST_TIME 47 9921
TEST_TIME 48 9825
TEST_TIME 49 9748
TEST_TIME 50 9638
TEST_TIME 51 9477
TEST_TIME 52 9485
TEST_TIME 53 9396
TEST_TIME 54 9316
TEST_TIME 55 9301
TEST_TIME 56 9270
TEST_TIME 57 9257
TEST_TIME 58 9332
TEST_TIME 59 9227
TEST_TIME 60 9169
TEST_TIME 61 9070
TEST_TIME 62 9003
TEST_TIME 63 9007
TEST_TIME 64 8941
TEST_TIME 65 8868
TEST_TIME 66 8906
TEST_TIME 67 8882
TEST_TIME 68 8843
TEST_TIME 69 8793
TEST_TIME 70 8781
TEST_TIME 71 8788
TEST_TIME 72 8772
TEST_TIME 73 8780
TEST_TIME 74 8947
TEST_TIME 75 8979
TEST_TIME 76 8970
TEST_TIME 77 9079
TEST_TIME 78 9153
TEST_TIME 79 9108
TEST_TIME 80 9154
TEST_TIME 81 9112
TEST_TIME 82 9026
TEST_TIME 83 9044
TEST_TIME 84 9030
TEST_TIME 85 9032
TEST_TIME 86 8956
TEST_TIME 87 8920
TEST_TIME 88 8920
TEST_TIME 89 8889
TEST_TIME 90 8823
TEST_TIME 91 8785
TEST_TIME 92 8765
TEST_TIME 93 8728
TEST_TIME 94 8689
TEST_TIME 95 8691
TEST_TIME 96 8720
TEST_TIME 97 8742
TEST_TIME 98 8709
TEST_TIME 99 8703
TEST_TIME 100 8774
TEST_TIME 101 8761
TEST_TIME 102 8780
TEST_TIME 103 8755
TEST_TIME 104 8750
TEST_TIME 105 8812
TEST_TIME 106 8852
TEST_TIME 107 8862
TEST_TIME 108 8839
TEST_TIME 109 8769
TEST_TIME 110 8744
TEST_TIME 111 8751
TEST_TIME 112 8708
TEST_TIME 113 8661
TEST_TIME 114 8675
TEST_TIME 115 8672
TEST_TIME 116 8649
TEST_TIME 117 8631
TEST_TIME 118 8621
TEST_TIME 119 8627
TEST_TIME 120 8565

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-04-2022 07:38:39 to 02-04-2022 07:38:42.

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 1426217 1426217 0 0.00 0 26797 14 0 0
doc_list_biud 1426203 1426203 0 0.00 0 27486 42 0 0
sp_add_invoice_to_stock 4819 4741 78 1.61 83 185893 26742 0 78
sp_cancel_adding_invoice 5310 5310 0 0.00 3 63124 1202 0 0
sp_cancel_client_order 1609 1609 0 0.00 7 7893 401 0 0
sp_cancel_customer_reserve 509697 509697 0 0.00 1 26224 101 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 4 2032 115 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 4 1527 113 0 0
sp_cancel_supplier_invoice 5959 5951 8 0.13 3 13705 989 0 8
sp_cancel_supplier_order 390 390 0 0.00 16 1397855 168124 0 0
sp_cancel_write_off 10153 10153 0 0.00 2 14194 160 0 0
sp_client_order 19197 19197 0 0.00 6 34196 506 0 0
sp_customer_reserve 434082 434082 0 0.00 1 52952 257 0 0
sp_fill_shopping_cart 29395 29395 0 0.00 2 27829 97 0 0
sp_fill_shopping_cart_clo_res 434062 434062 0 0.00 0 15169 21 0 0
sp_get_clo_for_invoice 4716 4716 0 0.00 0 50400 433 0 0
sp_kill_cost_storno 16534 16534 0 0.00 0 22080 75 0 0
sp_kill_qstorno_ret_qs2qd 1035888 1035888 0 0.00 0 23841 44 0 0
sp_kill_qty_storno 547149 547149 0 0.00 0 27486 102 0 0
sp_lock_selected_doc 19426 19426 0 0.00 0 8093 7 0 0
sp_make_cost_storno 26356 26356 0 0.00 0 15790 90 0 0
sp_multiply_rows_for_pdistr 26355 26355 0 0.00 0 26748 41 0 0
sp_multiply_rows_for_qdistr 19195 19195 0 0.00 0 34137 197 0 0
sp_pay_from_customer 6781 6781 0 0.00 6 13524 201 0 0
sp_pay_to_supplier 5534 5534 0 0.00 7 26810 220 0 0
sp_payment_common 12315 12315 0 0.00 2 12324 50 0 0
sp_qd_handle_on_cancel_clo 1609 1609 0 0.00 1 7868 324 0 0
sp_qd_handle_on_invoice_upd_sts 10026 10026 0 0.00 0 27484 554 0 0
sp_qd_handle_on_reserve_upd_sts 9323 9323 0 0.00 0 11535 68 0 0
sp_reserve_write_off 9325 9325 0 0.00 9 15474 285 0 0
sp_supplier_invoice 5210 5210 0 0.00 10 113847 3686 0 0
sp_supplier_order 4999 4999 0 0.00 9 34079 1399 0 0
srv_aggregate_perf_data 115 115 0 0.00 2458 200280 17944 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 727 123805 38088 0 0
srv_find_qd_qs_mism 979997 979997 0 0.00 0 23667 10 0 0
srv_make_invnt_saldo 432 432 0 0.00 54 99088 4372 0 0
srv_make_money_saldo 889 889 0 0.00 1 53283 412 0 0
srv_recalc_idx_stat 5 5 0 0.00 96058 460182 295814 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4744 4744 0 0.00 1 12547 998 0 0
t$perf-norm:sp_cancel_adding_invoice 355 355 0 0.00 1 6482 464 0 0
t$perf-norm:sp_cancel_client_order 1609 1609 0 0.00 0 2992 30 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 16053 68 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 641 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 747 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 841 35 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 105793 6823 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 8710 31 0 0
t$perf-norm:sp_client_order 19197 19197 0 0.00 0 7851 38 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 8856 25 0 0
t$perf-norm:sp_pay_from_customer 6782 6782 0 0.00 0 6646 23 0 0
t$perf-norm:sp_pay_to_supplier 5534 5534 0 0.00 0 15341 27 0 0
t$perf-norm:sp_reserve_write_off 9326 9326 0 0.00 0 8343 26 0 0
t$perf-norm:sp_supplier_invoice 5210 5210 0 0.00 0 7585 36 0 0
t$perf-norm:sp_supplier_order 4999 4999 0 0.00 0 7105 40 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 374 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 523 14 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 7018 36 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 185 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 175 73 0 0
v_add_invoice_to_stock 9572 9416 156 1.62 0 5609 27 0 156
v_all_customers 35526 35526 0 0.00 0 93 4 0 0
v_all_suppliers 28473 28473 0 0.00 0 70 4 0 0
v_all_wares 38384 38384 0 0.00 0 90 4 0 0
v_cancel_adding_invoice 710 710 0 0.00 0 1506 15 0 0
v_cancel_client_order 3218 3218 0 0.00 0 3898 14 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 1973 13 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 786 11 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 199 9 0 0
v_cancel_supplier_invoice 794 778 16 2.01 0 1340 25 0 16
v_cancel_supplier_order 780 780 0 0.00 0 1062 11 0 0
v_cancel_write_off 1866 1866 0 0.00 0 3137 31 0 0
v_max_id_clo_ord 4997 4997 0 0.00 0 480 5 0 0
v_max_id_clo_res 942 942 0 0.00 0 1722 12 0 0
v_max_id_ord_sup 5205 5205 0 0.00 0 3363 8 0 0
v_max_non_paid_invoice 1499 1499 0 0.00 0 2649 9 0 0
v_max_non_paid_realizn 3447 3447 0 0.00 0 1163 8 0 0
v_min_id_clo_ord 4997 4997 0 0.00 0 5296 10 0 0
v_min_id_clo_res 942 942 0 0.00 0 5105 21 0 0
v_min_id_ord_sup 5205 5205 0 0.00 0 9520 19 0 0
v_min_non_paid_invoice 5534 5534 0 0.00 0 12268 19 0 0
v_min_non_paid_realizn 6781 6781 0 0.00 0 8859 20 0 0
v_our_firm 9526 9526 0 0.00 0 235 5 0 0
v_reserve_write_off 18646 18646 0 0.00 0 3569 11 0 0
x_lock_depdocs_on_canc_invoice 10872 10872 0 0.00 0 16633 126 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 11372 363 0 0
x_make_qstorno_1000_1200 4999 4999 0 0.00 2 34009 1218 0 0
x_make_qstorno_1200_2000 5210 5210 0 0.00 4 112792 3406 0 0
x_make_qstorno_2100_3300 434082 434082 0 0.00 0 50028 223 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 02-04-2022 07:38:43 to 02-04-2022 07:38:48.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:17 25379 8192000000 9708060416 10004938752 789853824 8.793 100 87 174 0 819308512 7708384 21746304
05:09:45 22968 8192000000 9751479072 10057158656 826367392 9.163 100 92 184 0 861291616 11970848 22953376
05:11:19 23901 8192000000 9806734416 10127159296 865086496 9.551 100 89 178 2 914490880 28497072 20907312
05:12:48 13449 8192000000 9871393728 10212757504 922375472 10.120 100 72 142 1 978627984 40065264 16187248
05:14:05 5144 8192000000 9951773696 10302783488 979612048 10.680 100 90 170 1 1060913824 64215456 17086320
05:15:12 2098 8192000000 9975387408 10336559104 1012644704 11.001 100 85 164 1 1100538832 71325504 16568624
05:16:16 1356 8192000000 10013216192 10379632640 1037397712 11.240 100 93 182 2 1138254720 81224144 19632864
05:17:19 890 8192000000 10038137520 10409816064 1054140448 11.400 100 97 191 0 1157673536 84686208 18846880
05:18:23 740 8192000000 10045346672 10426257408 1061429872 11.470 100 92 174 1 1167898192 87999984 18468336
05:19:27 1528 8192000000 10056721616 10435207168 1065876688 11.513 100 92 179 0 1177598464 93780320 17941456
05:20:30 1188 8192000000 10044468880 10423738368 1077486736 11.624 100 96 183 1 1166071344 70187904 18396704
05:21:32 1332 8192000000 10056827840 10438443008 1084473296 11.690 100 97 183 2 1179007904 76341184 18193424
05:22:34 1021 8192000000 10047741264 10433822720 1089829504 11.741 100 88 170 1 1168572432 61577728 17165200
05:23:36 994 8192000000 10051845264 10443444224 1094874656 11.789 100 83 166 0 1176463936 64680592 16908688
05:24:37 5275 8192000000 10056420336 10453331968 1097881728 11.818 100 78 150 4 1190053696 77732752 14439216
05:25:44 1091 8192000000 10065005904 10463137792 1102681888 11.863 100 95 184 4 1196919936 74452000 19786048
05:26:46 1189 8192000000 10080212288 10470445056 1104937392 11.884 100 93 176 0 1202676304 78631872 19107040
05:27:48 1349 8192000000 10078352832 10467454976 1097955568 11.818 100 96 187 3 1199733408 82028544 19749296
05:28:51 1491 8192000000 10064310464 10448941056 1101405520 11.851 100 95 187 1 1185835392 64931808 19498064
05:29:53 989 8192000000 10041368656 10428973056 1089963072 11.742 100 94 187 0 1165562208 55685184 19913952
05:30:55 1281 8192000000 10060344048 10445807616 1085172416 11.697 100 98 190 0 1182146624 77493648 19480560
05:31:57 5748 8192000000 10040423856 10439950336 1068352944 11.536 100 71 135 2 1159562416 77155536 14053936
05:33:08 1364 8192000000 10010720240 10387156992 1046382656 11.326 100 90 168 4 1129166352 65195664 17588032
05:34:10 2985 8192000000 9966260992 10345467904 1015329456 11.027 98 67 126 3 1080585744 51765056 13491232
05:35:14 1109 8192000000 9997885200 10375782400 1036563728 11.232 99 91 165 1 1116126688 64011888 15551072
05:36:16 4321 8192000000 10001167968 10386735104 1042828720 11.292 100 60 114 0 1129657024 75145184 11683120
05:37:22 1173 8192000000 9998054080 10374324224 1062814064 11.483 100 88 166 4 1119843952 40412080 16617808
05:38:30 9586 8192000000 10024006512 10402578432 1071483552 11.566 100 82 147 3 1143285856 58297680 13504624
05:39:44 6605 8192000000 10056277808 10445361152 1092894912 11.770 100 66 130 0 1179455424 72825600 13734912
05:40:55 9679 8192000000 10107363600 10495107072 1108857584 11.922 100 74 142 1 1223210512 100050320 14302608
05:42:14 6965 8192000000 10128607600 10531336192 1124272016 12.067 100 55 105 2 1247918512 112344608 11301888
05:43:31 15889 8192000000 10185369920 10592251904 1128244112 12.105 100 58 116 0 1306411008 165231552 12935344
05:44:54 1981 8192000000 10210863632 10618888192 1142933936 12.243 100 93 176 1 1331106368 168498288 19674144
05:45:59 2408 8192000000 10216004496 10616946688 1151372240 12.322 100 79 153 0 1328930928 160600640 16958048
05:47:05 1026 8192000000 10254581856 10656903168 1155084896 12.357 100 87 170 3 1367186560 193129664 18972000
05:48:08 1099 8192000000 10285587104 10697080832 1171407504 12.510 100 94 182 0 1400713472 207380448 21925520
05:49:13 1463 8192000000 10308501312 10729312256 1180633280 12.596 100 96 183 1 1424668496 222522096 21513120
05:50:17 1533 8192000000 10292475584 10699202560 1180693248 12.597 100 89 178 1 1407388816 205563936 21131632
05:51:21 1253 8192000000 10321633072 10754912256 1185722288 12.644 100 94 184 1 1428090320 221134896 21233136
05:52:27 1175 8192000000 10338015200 10761142272 1193988800 12.720 100 94 179 1 1449226176 234241536 20995840
05:53:31 1238 8192000000 10370947376 10804183040 1199249520 12.769 100 93 183 1 1481708112 260390896 22067696
05:54:35 2179 8192000000 10386516688 10798751744 1202493536 12.799 100 86 172 1 1494538352 271805168 20239648
05:55:40 775 8192000000 10438774288 10864173056 1200509776 12.781 100 99 196 1 1545442720 320461776 24471168
05:56:43 711 8192000000 10467871968 10893017088 1191057936 12.693 100 96 193 1 1572532976 359087920 22387120
05:57:47 976 8192000000 10485992784 10901065728 1182003632 12.609 99 99 193 2 1593113536 388282912 22826992
05:58:51 865 8192000000 10452391392 10870771712 1142813888 12.242 99 95 178 0 1557952704 391949472 23189344
05:59:55 1036 8192000000 10482681904 10912505856 1171907952 12.515 100 94 185 0 1584857024 392265168 20683904
06:00:59 1086 8192000000 10445896800 10896990208 1168748176 12.485 100 74 141 0 1547138992 362154592 16236224
06:02:03 3115 8192000000 10429620272 10893971456 1182207584 12.611 100 65 127 0 1534354832 336477568 15669680
06:03:10 15633 8192000000 10448435632 10928607232 1179631968 12.587 100 44 86 1 1573661472 381966960 12062544
06:04:41 14688 8192000000 10454360512 10902327296 1174860032 12.542 100 39 78 1 1544260736 359360176 10040528
06:06:06 33634 8192000000 10438730272 10939244544 1158069616 12.385 99 29 58 1 1560808640 394923376 7815648
06:08:13 22228 8192000000 10356221840 10902364160 1116962544 11.998 98 25 50 0 1478374992 354626768 6785680
06:09:37 50626 8192000000 10123117152 10578124800 1105488320 11.890 96 23 45 0 1195353056 84992432 4872304
06:11:28 38226 8192000000 10147585232 10595524608 1145568640 12.268 100 40 80 0 1240085136 85264112 9252384
06:13:07 38736 8192000000 10182496976 10629459968 1137712576 12.194 99 27 53 3 1263766992 119495952 6558464
06:14:46 67641 8192000000 10165203744 10651844608 1112268144 11.954 97 18 36 1 1263301520 146546608 4486768
06:16:55 93593 8192000000 10075056592 10539700224 1117363552 12.002 98 26 51 0 1214187552 90968384 5855616
06:19:29 102000 8192000000 10136174560 10583117824 1106711088 11.901 96 27 53 0 1168119408 56129936 5278384
06:22:20 123805 8192000000 10122273120 10572328960 1126350496 12.087 99 30 60 1 1217169712 83902192 6917024
06:25:24 77610 8192000000 10084631072 10536976384 1124322976 12.068 98 36 70 0 1167739312 34959280 8457056
06:27:49 53026 8192000000 10122747296 10562830336 1142054672 12.235 100 44 84 3 1213922752 63043616 8824464
06:30:00 50844 8192000000 10121558992 10563383296 1127864656 12.101 98 33 65 0 1191748624 56646928 7237040
06:31:55 54045 8192000000 10151204464 10599362560 1149864176 12.308 100 40 80 1 1260693504 101611424 9217904
06:33:52 47981 8192000000 10142261856 10636849152 1132103168 12.141 99 13 25 1 1254360144 119024720 3232256
06:35:41 67922 8192000000 10066564352 10534727680 1133376336 12.153 100 11 23 0 1170145152 34072864 2695952
06:37:50 44279 8192000000 10055192352 10507100160 1139802240 12.214 100 17 33 1 1161543232 18154464 3586528
06:39:34 99905 8192000000 10078045360 10577018880 1140605424 12.221 99 23 45 1 1206951120 61863600 4482096
06:42:15 98501 8192000000 10072470224 10584760320 1124827056 12.073 98 13 26 1 1162655888 35503232 2325600
06:45:11 36272 8192000000 10071610576 10516922368 1128490400 12.107 99 26 52 1 1168511328 34947008 5073920
06:46:54 17319 8192000000 10143120416 10586963968 1159910720 12.402 100 45 88 1 1248964496 79874976 9178800
06:48:17 21039 8192000000 10158047584 10603778048 1130719904 12.128 98 39 78 1 1245427168 105478768 9228496
06:49:51 40751 8192000000 10204501040 10651783168 1121590128 12.042 97 23 46 1 1265732864 138396880 5745856
06:51:38 24713 8192000000 10175427920 10716545024 1144792864 12.261 99 21 42 1 1280707248 130192320 5722064
06:53:03 49588 8192000000 10175224736 10618679296 1118888832 12.016 97 47 92 1 1242625472 113614384 10122256
06:54:59 80835 8192000000 10098191248 10570059776 1078607616 11.634 95 16 32 2 1117066096 35916528 2541952
06:57:22 90196 8192000000 10131650576 10660392960 1101844256 11.855 98 16 31 1 1221136960 115827232 3465472
07:00:48 57467 8192000000 10039760768 10524028928 1109599056 11.929 99 9 16 1 1172146176 60680848 1866272
07:02:49 67929 8192000000 10051418144 10481803264 1066488288 11.519 95 19 38 1 1125210832 54742928 3979616
07:05:10 66955 8192000000 10047883712 10485940224 1100087792 11.838 99 14 26 2 1147600304 44325088 3187424
07:07:27 48164 8192000000 10058051904 10485596160 1103314256 11.869 99 41 80 0 1148318864 35723632 9280976
07:09:18 76147 8192000000 10052448608 10499911680 1084253136 11.688 97 9 17 0 1128322768 42525744 1543888
07:11:34 71640 8192000000 10066118848 10538024960 1097042368 11.810 98 11 21 2 1145464688 46449360 1972960
07:13:47 59830 8192000000 10061010496 10494521344 1121480480 12.041 100 18 34 0 1170388016 45554176 3353360
07:15:49 27555 8192000000 10123872864 10555432960 1118754832 12.015 99 52 104 0 1190081136 60581952 10744352
07:17:16 57806 8192000000 10072311472 10563461120 1110126672 11.934 99 23 46 3 1174069856 59313328 4629856
07:19:20 13479 8192000000 10106576528 10537811968 1122653296 12.052 100 56 110 0 1202963216 69177392 11132528
07:20:35 38146 8192000000 10122996576 10553303040 1110743248 11.939 98 37 74 0 1205297456 86947072 7607136
07:22:14 76024 8192000000 10075382496 10560081920 1122948720 12.055 99 19 38 0 1211397120 84640288 3808112
07:24:43 34480 8192000000 10097510048 10533027840 1113460656 11.965 98 45 88 1 1174503648 52364048 8678944
07:26:20 65680 8192000000 10091454944 10525179904 1134682320 12.165 100 57 110 2 1203618816 57577344 11359152
07:28:26 76400 8192000000 10059802720 10512924672 1101853504 11.855 97 14 28 1 1146180272 41941376 2385392
07:30:44 83275 8192000000 10068512400 10577543168 1129669936 12.118 100 5 10 0 1198247520 66976800 1600784
07:33:18 54658 8192000000 10074274128 10535813120 1115461072 11.984 98 16 32 0 1178006176 59277648 3267456
07:35:13 33182 8192000000 10111898944 10558099456 1134005024 12.159 99 23 46 2 1221161392 82054544 5101824
07:36:47 27654 8192000000 9797846960 10180132864 823582080 9.135 72 33 64 1 918573792 87867232 7124480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-04-2022 07:38:49 to 02-04-2022 07:39:05.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-04-2022 07:39:06 to 02-04-2022 07:39:08.

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          208490
MON$OLDEST_ACTIVE               214677
MON$OLDEST_SNAPSHOT             214677
MON$NEXT_TRANSACTION            214677
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                       2076128
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:358: 
:359: mscs-test-161.budzdorov.ru    Sat Apr  2 07:37:18 2022
:360:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:361: 
:362: 
:363: mscs-test-161.budzdorov.ru    Sat Apr  2 07:37:28 2022
:364:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:365: 
--- end of diff output ---

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

02.04.22 07:39:09. 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
2022-04-02 07:39:12 No severe PSQL-related problems occured
02.04.22 07:39:13. End of report.