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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16349816 kB
MemAvailable 31260584 kB
Buffers 20 kB
Cached 15255960 kB
SwapCached 1208 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 2022-01-07 04:37:13
DTS_END                         2022-01-07 04:37:13.1910
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/34932
TRN_ID                          219114
ATT_ID                          609
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: 8955.98 29337 1074717
customer order: creation 166.87 522 20024
customer order: refuse 15.01 396 1801
order to supplier: creation 44.03 1400 5284
order to supplier: removal 3.41 164751 409
invoice (draft): creation 44.29 3100 5315
invoice (draft): removal 47.92 1044 5750
invoice accept: apply 41.52 27744 4982
invoice accept: cancel 43.84 1213 5261
customer reserve: creation 3916.01 263 469921
customer reserve: removal 4349.78 101 521974
realization accept: apply 81.29 278 9755
realization accept: cancel 74.37 179 8924
payment from customer: creation 57.76 193 6931
payment from customer: removal 5.64 116 677
payment to supplier: creation 48.49 203 5819
payment to supplier: removal 3.39 90 407
service: total inventory turnovers 3.88 4287 465
service: total monetary turnovers 7.43 335 892
service: aggregate perf. data 1.01 12778 121
service: refresh index statistics 0.04 367742 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-01-2022 04:38:25 to 07-01-2022 04:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8434
TEST_TIME 2 6100
TEST_TIME 3 5636
TEST_TIME 4 5552
TEST_TIME 5 6442
TEST_TIME 6 5904
TEST_TIME 7 6069
TEST_TIME 8 6207
TEST_TIME 9 6426
TEST_TIME 10 6657
TEST_TIME 11 6737
TEST_TIME 12 6865
TEST_TIME 13 7047
TEST_TIME 14 7057
TEST_TIME 15 7022
TEST_TIME 16 7163
TEST_TIME 17 7258
TEST_TIME 18 7426
TEST_TIME 19 7436
TEST_TIME 20 7554
TEST_TIME 21 7740
TEST_TIME 22 7859
TEST_TIME 23 8088
TEST_TIME 24 8559
TEST_TIME 25 8556
TEST_TIME 26 8537
TEST_TIME 27 8828
TEST_TIME 28 9010
TEST_TIME 29 9720
TEST_TIME 30 9980
TEST_TIME 31 10561
TEST_TIME 32 11027
TEST_TIME 33 11027
TEST_TIME 34 11097
TEST_TIME 35 10923
TEST_TIME 36 10932
TEST_TIME 37 10786
TEST_TIME 38 10652
TEST_TIME 39 10408
TEST_TIME 40 10350
TEST_TIME 41 10314
TEST_TIME 42 10188
TEST_TIME 43 10150
TEST_TIME 44 10009
TEST_TIME 45 9996
TEST_TIME 46 10121
TEST_TIME 47 9980
TEST_TIME 48 9967
TEST_TIME 49 9801
TEST_TIME 50 9957
TEST_TIME 51 9858
TEST_TIME 52 9836
TEST_TIME 53 9874
TEST_TIME 54 9891
TEST_TIME 55 9941
TEST_TIME 56 9869
TEST_TIME 57 9877
TEST_TIME 58 9870
TEST_TIME 59 9963
TEST_TIME 60 9978
TEST_TIME 61 9942
TEST_TIME 62 9990
TEST_TIME 63 9948
TEST_TIME 64 9802
TEST_TIME 65 9720
TEST_TIME 66 9834
TEST_TIME 67 9769
TEST_TIME 68 9745
TEST_TIME 69 9652
TEST_TIME 70 9558
TEST_TIME 71 9454
TEST_TIME 72 9395
TEST_TIME 73 9400
TEST_TIME 74 9329
TEST_TIME 75 9278
TEST_TIME 76 9370
TEST_TIME 77 9324
TEST_TIME 78 9362
TEST_TIME 79 9380
TEST_TIME 80 9379
TEST_TIME 81 9376
TEST_TIME 82 9386
TEST_TIME 83 9406
TEST_TIME 84 9427
TEST_TIME 85 9407
TEST_TIME 86 9389
TEST_TIME 87 9514
TEST_TIME 88 9437
TEST_TIME 89 9376
TEST_TIME 90 9301
TEST_TIME 91 9333
TEST_TIME 92 9271
TEST_TIME 93 9255
TEST_TIME 94 9188
TEST_TIME 95 9146
TEST_TIME 96 9165
TEST_TIME 97 9179
TEST_TIME 98 9146
TEST_TIME 99 9144
TEST_TIME 100 9111
TEST_TIME 101 9077
TEST_TIME 102 9146
TEST_TIME 103 9120
TEST_TIME 104 9109
TEST_TIME 105 9225
TEST_TIME 106 9245
TEST_TIME 107 9248
TEST_TIME 108 9178
TEST_TIME 109 9134
TEST_TIME 110 9138
TEST_TIME 111 9113
TEST_TIME 112 9088
TEST_TIME 113 9051
TEST_TIME 114 9058
TEST_TIME 115 9069
TEST_TIME 116 9038
TEST_TIME 117 9018
TEST_TIME 118 9025
TEST_TIME 119 9048
TEST_TIME 120 8980

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2022 04:38:29 to 07-01-2022 04:38:32.

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 1505456 1505456 0 0.00 0 36152 14 0 0
doc_list_biud 1505443 1505443 0 0.00 0 41471 41 0 0
sp_add_invoice_to_stock 5177 4982 195 3.76 31 208655 27745 0 195
sp_cancel_adding_invoice 5261 5261 0 0.00 6 113731 1213 0 0
sp_cancel_client_order 1801 1801 0 0.00 6 15553 396 0 0
sp_cancel_customer_reserve 521974 521974 0 0.00 1 35578 102 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 4 4987 117 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 1243 90 0 0
sp_cancel_supplier_invoice 5764 5750 14 0.24 4 17534 1045 0 14
sp_cancel_supplier_order 409 409 0 0.00 9 1463632 164751 0 0
sp_cancel_write_off 8924 8924 0 0.00 2 9804 179 0 0
sp_client_order 20024 20024 0 0.00 5 36376 523 0 0
sp_customer_reserve 469921 469921 0 0.00 1 101093 264 0 0
sp_fill_shopping_cart 30614 30614 0 0.00 2 16010 76 0 0
sp_fill_shopping_cart_clo_res 469896 469896 0 0.00 0 21437 20 0 0
sp_get_clo_for_invoice 4947 4947 0 0.00 0 25417 299 0 0
sp_kill_cost_storno 15269 15269 0 0.00 0 28515 73 0 0
sp_kill_qstorno_ret_qs2qd 1059031 1059031 0 0.00 0 35480 44 0 0
sp_kill_qty_storno 558818 558818 0 0.00 0 41468 102 0 0
sp_lock_selected_doc 20365 20365 0 0.00 0 7738 7 0 0
sp_make_cost_storno 27451 27451 0 0.00 0 36149 84 0 0
sp_multiply_rows_for_pdistr 27450 27450 0 0.00 0 8556 39 0 0
sp_multiply_rows_for_qdistr 20024 20024 0 0.00 0 9915 196 0 0
sp_pay_from_customer 6931 6931 0 0.00 6 36160 193 0 0
sp_pay_to_supplier 5819 5819 0 0.00 6 15516 203 0 0
sp_payment_common 12747 12747 0 0.00 2 7182 41 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 15543 321 0 0
sp_qd_handle_on_invoice_upd_sts 10207 10207 0 0.00 0 41466 532 0 0
sp_qd_handle_on_reserve_upd_sts 9753 9753 0 0.00 0 18003 67 0 0
sp_reserve_write_off 9755 9755 0 0.00 8 29562 278 0 0
sp_supplier_invoice 5315 5315 0 0.00 8 93282 3101 0 0
sp_supplier_order 5284 5284 0 0.00 7 44868 1401 0 0
srv_aggregate_perf_data 121 121 0 0.00 2621 168168 12779 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 832 98899 36687 0 0
srv_find_qd_qs_mism 1025225 1025225 0 0.00 0 23412 12 0 0
srv_make_invnt_saldo 465 465 0 0.00 52 141292 4288 0 0
srv_make_money_saldo 892 892 0 0.00 1 10415 335 0 0
srv_recalc_idx_stat 5 5 0 0.00 211371 567017 367742 0 0
t$perf-abend:sp_add_invoice_to_stock 195 195 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 12752 839 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 8452 421 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 2696 28 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 964 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 1486 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 594 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 1237 46 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 81435 6200 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 961 15 0 0
t$perf-norm:sp_client_order 20025 20025 0 0.00 0 9227 32 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 930 14 0 0
t$perf-norm:sp_pay_from_customer 6931 6931 0 0.00 0 4956 16 0 0
t$perf-norm:sp_pay_to_supplier 5819 5819 0 0.00 0 6576 17 0 0
t$perf-norm:sp_reserve_write_off 9755 9755 0 0.00 0 2693 19 0 0
t$perf-norm:sp_supplier_invoice 5315 5315 0 0.00 0 9108 31 0 0
t$perf-norm:sp_supplier_order 5284 5284 0 0.00 0 3728 29 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 700 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 69 6 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 943 16 0 0
t$perf-norm:srv_make_money_saldo 892 892 0 0.00 0 2952 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 213 92 0 0
v_add_invoice_to_stock 10270 9880 390 3.79 0 15920 25 0 390
v_all_customers 36728 36728 0 0.00 0 90 3 0 0
v_all_suppliers 30053 30053 0 0.00 0 153 4 0 0
v_all_wares 40035 40035 0 0.00 0 52 3 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 688 13 0 0
v_cancel_client_order 3602 3602 0 0.00 0 2942 11 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 4980 15 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 2075 14 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1204 11 0 0
v_cancel_supplier_invoice 728 700 28 3.84 0 954 26 0 28
v_cancel_supplier_order 818 818 0 0.00 0 2845 17 0 0
v_cancel_write_off 1974 1974 0 0.00 0 9583 27 0 0
v_max_id_clo_ord 5284 5284 0 0.00 0 496 5 0 0
v_max_id_clo_res 998 998 0 0.00 0 1195 12 0 0
v_max_id_ord_sup 5310 5310 0 0.00 0 8826 8 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 1997 8 0 0
v_max_non_paid_realizn 3559 3559 0 0.00 0 1997 7 0 0
v_min_id_clo_ord 5284 5284 0 0.00 0 956 7 0 0
v_min_id_clo_res 998 998 0 0.00 0 907 7 0 0
v_min_id_ord_sup 5310 5310 0 0.00 0 3677 7 0 0
v_min_non_paid_invoice 5817 5817 0 0.00 0 5442 14 0 0
v_min_non_paid_realizn 6930 6930 0 0.00 0 6190 16 0 0
v_our_firm 10042 10042 0 0.00 0 55 4 0 0
v_reserve_write_off 19504 19504 0 0.00 0 2942 11 0 0
x_lock_depdocs_on_canc_invoice 10661 10661 0 0.00 0 10265 123 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 9707 363 0 0
x_make_qstorno_1000_1200 5284 5284 0 0.00 3 44856 1235 0 0
x_make_qstorno_1200_2000 5315 5315 0 0.00 3 93260 2893 0 0
x_make_qstorno_2100_3300 469919 469919 0 0.00 0 101023 232 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-01-2022 04:38:33 to 07-01-2022 04:38:38.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:18 37824 8192000000 9723391024 10021535744 803249488 8.929 100 86 173 0 832708272 8228080 21230704
02:10:03 19809 8192000000 9762208848 10069397504 838439888 9.284 100 89 178 0 873546304 13910688 21195728
02:11:36 12083 8192000000 9810869536 10132189184 869625728 9.596 100 75 150 0 916400640 29752624 17022288
02:12:56 9899 8192000000 9891910064 10223153152 923870640 10.134 100 92 184 0 1002125456 57925824 20328992
02:14:09 5783 8192000000 9925381744 10268745728 956138944 10.451 100 77 151 0 1030205728 58693152 15373632
02:15:18 2930 8192000000 9969303328 10322014208 996436336 10.844 100 93 186 0 1082513376 67062672 19014368
02:16:24 5766 8192000000 9990818704 10355126272 1022877856 11.100 100 85 158 0 1109302112 72103376 14320880
02:17:32 928 8192000000 10026149408 10394959872 1048673744 11.348 100 96 187 0 1145307488 78919056 17714688
02:18:35 1197 8192000000 10022464928 10394234880 1059623872 11.453 100 93 182 0 1139105120 60600848 18880400
02:19:37 821 8192000000 10038604320 10414252032 1064782608 11.502 100 98 190 2 1160798528 77010032 19005888
02:20:39 1149 8192000000 10038635472 10420494336 1072108448 11.572 100 92 177 5 1160869952 69847520 18913984
02:21:41 1246 8192000000 10036334272 10416771072 1076409568 11.613 100 86 168 0 1155767360 62127312 17230480
02:22:47 2289 8192000000 10024255520 10410053632 1081864752 11.665 100 73 139 0 1145683104 49710192 14108160
02:23:50 6726 8192000000 10050162304 10447147008 1082282480 11.669 100 64 118 0 1162571552 68156608 12132464
02:24:57 943 8192000000 10062745056 10450567168 1092072128 11.762 100 83 160 1 1183755104 74956976 16726000
02:26:00 1386 8192000000 10048354288 10433794048 1087685616 11.721 100 94 175 2 1170908592 63830736 19392240
02:27:02 2909 8192000000 10065040112 10454872064 1095309936 11.793 100 62 124 0 1188254576 80978400 11966240
02:28:05 1075 8192000000 10053232304 10446430208 1100083920 11.838 100 85 159 3 1175722864 59389360 16249584
02:29:07 1237 8192000000 10045210624 10429870080 1091621376 11.758 100 92 171 1 1164995808 57254368 16120064
02:30:09 986 8192000000 10039543008 10422026240 1089433968 11.737 100 92 175 2 1163836704 55964272 18438464
02:31:11 2040 8192000000 10019204288 10403373056 1068334960 11.536 100 79 154 6 1143189440 58588592 16265888
02:32:14 1281 8192000000 9980608416 10350698496 1030868320 11.177 97 84 155 2 1103570880 57120912 15581648
02:33:16 1051 8192000000 9985514352 10357174272 1025880736 11.129 99 86 161 4 1107358384 64850944 16626704
02:34:19 1350 8192000000 10009883424 10384437248 1051824160 11.378 100 93 181 3 1135135792 64996400 18315232
02:35:21 2120 8192000000 9968524480 10346323968 1027601280 11.145 99 68 125 3 1097333312 56933568 12798464
02:36:24 973 8192000000 9992083984 10366136320 1037664976 11.242 100 92 177 0 1114550128 58717440 18167712
02:37:29 12705 8192000000 9977756816 10348806144 1025933552 11.129 99 70 130 3 1090533072 51284736 13314784
02:38:49 25421 8192000000 10009801792 10387202048 1041540032 11.279 98 61 120 0 1127383328 73985456 11857840
02:40:23 2833 8192000000 10023286480 10396606464 1054148336 11.400 99 84 159 0 1147315136 78500288 14666512
02:41:42 18576 8192000000 10073939904 10474729472 1075976752 11.609 100 64 124 1 1201265456 111920000 13368704
02:43:07 10183 8192000000 10080155184 10475569152 1093603392 11.777 100 54 107 1 1194473856 89979776 10890688
02:44:20 1082 8192000000 10124995088 10513326080 1106865312 11.903 100 86 160 0 1242347920 118463920 17018688
02:45:27 1107 8192000000 10167790992 10552459264 1121228048 12.039 100 98 193 1 1278677264 135771552 21677664
02:46:29 1219 8192000000 10223691728 10613354496 1142258944 12.237 100 96 191 0 1335532144 172925024 20348176
02:47:34 2886 8192000000 10239412464 10648293376 1157247248 12.377 100 86 172 0 1356501680 180276176 18978256
02:48:45 2568 8192000000 10271000496 10678280192 1155558592 12.362 99 84 165 0 1389339520 214804304 18976624
02:49:52 1119 8192000000 10293596832 10699059200 1174523776 12.539 100 97 187 0 1404417408 208491904 21401728
02:50:59 1060 8192000000 10319722176 10728148992 1187228464 12.658 100 95 185 1 1428602400 220226272 21147664
02:52:05 1019 8192000000 10353425392 10753970176 1191170416 12.694 100 92 184 1 1462025888 249594736 21260736
02:53:08 825 8192000000 10405456336 10816962560 1207091152 12.842 100 99 197 1 1514015472 282718576 24205744
02:54:12 1280 8192000000 10448756912 10859962368 1200400016 12.780 100 95 186 0 1554228192 332297632 21530544
02:55:16 889 8192000000 10490196704 10910027776 1200496656 12.781 100 95 188 0 1596686960 373754096 22436208
02:56:21 1073 8192000000 10509276672 10919346176 1191820704 12.700 100 98 190 2 1613712560 399190400 22701456
02:57:24 1450 8192000000 10535763856 10973974528 1201053728 12.786 100 98 196 0 1636524736 412882672 22588336
02:58:27 1867 8192000000 10554561120 10964803584 1200626880 12.782 100 84 168 1 1658085072 437410160 20048032
02:59:33 2326 8192000000 10571871392 11021594624 1192978000 12.711 100 83 159 1 1674011936 460089504 20944432
03:00:37 3256 8192000000 10581498352 11027226624 1203380288 12.808 100 82 161 0 1690804016 467640208 19783520
03:01:53 4881 8192000000 10547938576 10969853952 1198216960 12.760 100 63 126 0 1647784240 434501808 15065472
03:03:02 4040 8192000000 10572584720 11011067904 1190123120 12.685 100 67 131 3 1675192272 469267488 15801664
03:04:19 7619 8192000000 10550085856 11030753280 1170783504 12.504 100 45 89 2 1647283648 464700896 11799248
03:06:12 16371 8192000000 10393199808 10886946816 1159869584 12.402 100 35 70 0 1513007184 343782416 9355184
03:08:02 23206 8192000000 10282612192 10826850304 1159443760 12.398 100 20 40 0 1387239776 222237616 5558400
03:09:33 52388 8192000000 10095365840 10580713472 1114157088 11.972 98 10 20 1 1180831984 64540192 2134704
03:12:01 37651 8192000000 10074303104 10513002496 1116444368 11.993 98 33 59 2 1150690960 28694352 5552240
03:13:39 48831 8192000000 10068441424 10508353536 1138470000 12.201 100 37 69 3 1167026352 21217456 7338896
03:15:28 88434 8192000000 10098154416 10541731840 1133199344 12.152 100 45 88 2 1185377072 42137344 10040384
03:18:01 98502 8192000000 10069283536 10509713408 1109096320 11.924 98 19 38 1 1161724352 48824432 3803600
03:20:41 38035 8192000000 10117724240 10552459264 1108549904 11.919 98 33 64 0 1192779808 76409696 7820208
03:22:27 21819 8192000000 10129855104 10587463680 1120669248 12.033 99 52 95 1 1246624000 117043632 8911120
03:24:07 64722 8192000000 10088178160 10559000576 1125357072 12.078 99 23 39 2 1181671536 52232320 4082144
03:26:12 33606 8192000000 10107139760 10566426624 1137221424 12.189 100 13 27 0 1210866384 70949472 2695488
03:27:49 48201 8192000000 10060667440 10520428544 1113370288 11.964 98 14 24 0 1149171872 34042384 1759200
03:29:38 79458 8192000000 10123848368 10602696704 1099403616 11.832 96 19 36 0 1163489936 59514736 4571584
03:32:21 54293 8192000000 10071966992 10505789440 1108611904 11.919 98 28 56 1 1145643712 31601472 5430336
03:34:16 25037 8192000000 10167000896 10603028480 1118830880 12.016 99 59 118 2 1233533456 103311232 11391344
03:36:00 32196 8192000000 10103035456 10564005888 1091732496 11.759 97 37 73 1 1199764224 100487632 7544096
03:37:34 65433 8192000000 10128177488 10566946816 1105957040 11.894 98 32 63 2 1176006144 64042464 6006640
03:39:44 39723 8192000000 10126968384 10571456512 1105077504 11.886 97 29 58 1 1195500976 84316048 6107424
03:41:29 98897 8192000000 10136271968 10577915904 1118114752 12.009 99 26 52 0 1214810864 90850688 5845424
03:44:08 74084 8192000000 10062636336 10532184064 1135012928 12.169 100 15 30 0 1179798096 41708064 3077104
03:46:34 71112 8192000000 10113870880 10562101248 1129139808 12.113 98 21 40 2 1188924208 54081776 5702624
03:48:46 71123 8192000000 10136941520 10619404288 1139393248 12.210 100 18 36 1 1239322240 95960128 3968864
03:50:58 92767 8192000000 10112556240 10567254016 1106484800 11.899 97 13 23 1 1181655344 72790880 2379664
03:53:31 30098 8192000000 10048454400 10483490816 1095648560 11.796 96 22 39 0 1119485024 20448384 3388080
03:55:01 72205 8192000000 10115745424 10585833472 1094483040 11.785 96 38 72 1 1176520352 75073344 6963968
03:57:14 12546 8192000000 10104157920 10529624064 1127090464 12.094 99 63 122 0 1180719520 39743920 13885136
03:58:28 20488 8192000000 10094058112 10523799552 1118647552 12.014 99 42 84 2 1180450864 53489568 8313744
03:59:49 30961 8192000000 10115024160 10566344704 1118942448 12.017 98 48 95 0 1190126976 61848512 9336016
04:01:21 75177 8192000000 10066442000 10496475136 1054633648 11.405 93 36 69 2 1106341120 44649872 7057600
04:03:38 21594 8192000000 10173290096 10617151488 1139696064 12.213 100 41 78 2 1268797792 121211200 7890528
04:05:04 90671 8192000000 10087257872 10528456704 1119184416 12.019 98 35 68 1 1171874624 44822256 7867952
04:07:40 15232 8192000000 10084731808 10531278848 1120931984 12.036 99 36 69 1 1191599056 62372672 8294400
04:09:06 67384 8192000000 10081217168 10530291712 1115872128 11.988 98 17 34 2 1146171200 26195040 4104032
04:11:28 82724 8192000000 10089525024 10529779712 1122629840 12.052 99 12 24 0 1175203840 49722032 2851968
04:13:56 59128 8192000000 10096448224 10559283200 1134945504 12.168 99 20 40 0 1185239792 45769552 4524736
04:16:07 21446 8192000000 10154692576 10592493568 1147799120 12.289 100 43 86 1 1235401456 78679808 8922528
04:17:29 38539 8192000000 10158186448 10585444352 1096585104 11.805 96 41 81 0 1207179072 102026064 8567904
04:19:18 60568 8192000000 10103149152 10545975296 1106674176 11.901 98 24 48 2 1199877520 87096832 6106512
04:21:20 71064 8192000000 10136446416 10614173696 1088147712 11.725 97 36 72 1 1217929888 122028640 7753536
04:23:34 70444 8192000000 10043251760 10479120384 1083821312 11.684 97 10 20 0 1128785296 43476000 1487984
04:25:44 98411 8192000000 10069692464 10552438784 1130288112 12.124 100 21 42 0 1191987968 57719616 3980240
04:29:54 48103 8192000000 10086124240 10538237952 1135998352 12.178 99 24 46 0 1187070848 46184240 4888256
04:31:42 74979 8192000000 10081626672 10542030848 1152428368 12.332 100 16 32 0 1218024416 62255488 3340560
04:34:01 35303 8192000000 10120988752 10568814592 1119268752 12.020 97 29 58 1 1201609776 76176656 6164368
04:35:37 46855 8192000000 10149022976 10640146432 1143615120 12.250 99 24 48 1 1228503440 79578160 5310160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 195 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2022 04:38:56 to 07-01-2022 04:38:58.

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          216320
MON$OLDEST_ACTIVE               219725
MON$OLDEST_SNAPSHOT             219725
MON$NEXT_TRANSACTION            219725
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                       2042144
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Fri Jan  7 04:37:13 2022
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Fri Jan  7 04:37:14 2022
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Fri Jan  7 04:37:14 2022
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Fri Jan  7 04:37:17 2022
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Fri Jan  7 04:37:20 2022
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
--- end of diff output ---

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

07.01.22 04:38:59. 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-01-07 04:39:02 No severe PSQL-related problems occured
07.01.22 04:39:03. End of report.