Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.2022 10: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 15601916 kB
MemAvailable 30547836 kB
Buffers 0 kB
Cached 16098680 kB
SwapCached 2040 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.3
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-10-03 10:37:22
DTS_END                         2022-10-03 10:37:22.6560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51794
TRN_ID                          225871
ATT_ID                          660
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: 8936.75 24431 1072410
customer order: creation 172.23 501 20668
customer order: refuse 14.93 364 1792
order to supplier: creation 45.74 1366 5489
order to supplier: removal 3.16 163566 379
invoice (draft): creation 46.60 3106 5592
invoice (draft): removal 49.15 947 5898
invoice accept: apply 41.44 25896 4973
invoice accept: cancel 44.21 1047 5305
customer reserve: creation 3878.38 249 465405
customer reserve: removal 4349.32 94 521918
realization accept: apply 84.22 260 10106
realization accept: cancel 76.89 162 9227
payment from customer: creation 59.03 163 7084
payment from customer: removal 5.87 116 704
payment to supplier: creation 49.49 181 5939
payment to supplier: removal 3.40 98 408
service: total inventory turnovers 4.28 3141 513
service: total monetary turnovers 7.39 332 887
service: aggregate perf. data 0.99 12941 119
service: refresh index statistics 0.03 274093 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2022 10:38:39 to 03-10-2022 10:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6896
TEST_TIME 2 6644
TEST_TIME 3 5821
TEST_TIME 4 5554
TEST_TIME 5 5534
TEST_TIME 6 5511
TEST_TIME 7 6146
TEST_TIME 8 6022
TEST_TIME 9 5692
TEST_TIME 10 5608
TEST_TIME 11 6036
TEST_TIME 12 6080
TEST_TIME 13 6190
TEST_TIME 14 6071
TEST_TIME 15 6457
TEST_TIME 16 7058
TEST_TIME 17 7320
TEST_TIME 18 7694
TEST_TIME 19 7675
TEST_TIME 20 7723
TEST_TIME 21 7997
TEST_TIME 22 8134
TEST_TIME 23 8153
TEST_TIME 24 8398
TEST_TIME 25 8721
TEST_TIME 26 8734
TEST_TIME 27 8991
TEST_TIME 28 9698
TEST_TIME 29 9955
TEST_TIME 30 10867
TEST_TIME 31 10995
TEST_TIME 32 10812
TEST_TIME 33 10698
TEST_TIME 34 10664
TEST_TIME 35 10402
TEST_TIME 36 10233
TEST_TIME 37 10240
TEST_TIME 38 10062
TEST_TIME 39 10063
TEST_TIME 40 9956
TEST_TIME 41 10017
TEST_TIME 42 9943
TEST_TIME 43 9893
TEST_TIME 44 9740
TEST_TIME 45 9624
TEST_TIME 46 9487
TEST_TIME 47 9473
TEST_TIME 48 9659
TEST_TIME 49 9744
TEST_TIME 50 9673
TEST_TIME 51 9687
TEST_TIME 52 9714
TEST_TIME 53 9722
TEST_TIME 54 9804
TEST_TIME 55 9799
TEST_TIME 56 9824
TEST_TIME 57 9788
TEST_TIME 58 9893
TEST_TIME 59 9909
TEST_TIME 60 9783
TEST_TIME 61 9717
TEST_TIME 62 9678
TEST_TIME 63 9636
TEST_TIME 64 9647
TEST_TIME 65 9591
TEST_TIME 66 9510
TEST_TIME 67 9400
TEST_TIME 68 9390
TEST_TIME 69 9320
TEST_TIME 70 9229
TEST_TIME 71 9341
TEST_TIME 72 9348
TEST_TIME 73 9303
TEST_TIME 74 9317
TEST_TIME 75 9336
TEST_TIME 76 9309
TEST_TIME 77 9260
TEST_TIME 78 9220
TEST_TIME 79 9380
TEST_TIME 80 9478
TEST_TIME 81 9554
TEST_TIME 82 9497
TEST_TIME 83 9521
TEST_TIME 84 9539
TEST_TIME 85 9463
TEST_TIME 86 9435
TEST_TIME 87 9401
TEST_TIME 88 9373
TEST_TIME 89 9319
TEST_TIME 90 9289
TEST_TIME 91 9225
TEST_TIME 92 9172
TEST_TIME 93 9126
TEST_TIME 94 9206
TEST_TIME 95 9157
TEST_TIME 96 9160
TEST_TIME 97 9150
TEST_TIME 98 9196
TEST_TIME 99 9176
TEST_TIME 100 9184
TEST_TIME 101 9155
TEST_TIME 102 9187
TEST_TIME 103 9207
TEST_TIME 104 9162
TEST_TIME 105 9260
TEST_TIME 106 9245
TEST_TIME 107 9168
TEST_TIME 108 9165
TEST_TIME 109 9134
TEST_TIME 110 9105
TEST_TIME 111 9059
TEST_TIME 112 9016
TEST_TIME 113 9010
TEST_TIME 114 8995
TEST_TIME 115 8990
TEST_TIME 116 8968
TEST_TIME 117 9032
TEST_TIME 118 9013
TEST_TIME 119 9005
TEST_TIME 120 8958

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-10-2022 10:38:42 to 03-10-2022 10:38:46.

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 1501017 1501017 0 0.00 0 22055 12 0 0
doc_list_biud 1501009 1501009 0 0.00 0 55803 38 0 0
sp_add_invoice_to_stock 5113 4973 140 2.73 48 216255 25896 0 140
sp_cancel_adding_invoice 5305 5305 0 0.00 4 59509 1048 0 0
sp_cancel_client_order 1792 1792 0 0.00 6 8060 365 0 0
sp_cancel_customer_reserve 521918 521918 0 0.00 1 33283 95 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 4 7515 116 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 2066 99 0 0
sp_cancel_supplier_invoice 5904 5898 6 0.10 2 55820 947 0 6
sp_cancel_supplier_order 379 379 0 0.00 7 1223695 163567 0 0
sp_cancel_write_off 9227 9227 0 0.00 2 13873 162 0 0
sp_client_order 20668 20668 0 0.00 5 30160 502 0 0
sp_customer_reserve 465405 465405 0 0.00 0 66855 250 0 0
sp_fill_shopping_cart 31732 31732 0 0.00 2 15994 72 0 0
sp_fill_shopping_cart_clo_res 465379 465379 0 0.00 0 21194 20 0 0
sp_get_clo_for_invoice 4930 4930 0 0.00 0 24579 296 0 0
sp_kill_cost_storno 15644 15644 0 0.00 0 10849 64 0 0
sp_kill_qstorno_ret_qs2qd 1059340 1059340 0 0.00 0 55797 43 0 0
sp_kill_qty_storno 559554 559554 0 0.00 0 55800 96 0 0
sp_lock_selected_doc 20841 20841 0 0.00 0 19662 8 0 0
sp_make_cost_storno 28059 28059 0 0.00 0 22052 67 0 0
sp_multiply_rows_for_pdistr 28059 28059 0 0.00 0 8840 35 0 0
sp_multiply_rows_for_qdistr 20666 20666 0 0.00 0 25179 210 0 0
sp_pay_from_customer 7084 7084 0 0.00 6 16687 164 0 0
sp_pay_to_supplier 5939 5939 0 0.00 6 24749 182 0 0
sp_payment_common 13022 13022 0 0.00 2 9875 40 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 1 8048 300 0 0
sp_qd_handle_on_invoice_upd_sts 10234 10234 0 0.00 0 12972 429 0 0
sp_qd_handle_on_reserve_upd_sts 10106 10106 0 0.00 0 23803 62 0 0
sp_reserve_write_off 10106 10106 0 0.00 9 23819 261 0 0
sp_supplier_invoice 5592 5592 0 0.00 12 95028 3106 0 0
sp_supplier_order 5489 5489 0 0.00 8 44720 1366 0 0
srv_aggregate_perf_data 119 119 0 0.00 2558 156627 12942 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 805 105017 34442 0 0
srv_find_qd_qs_mism 1023231 1023231 0 0.00 0 20696 10 0 0
srv_make_invnt_saldo 513 513 0 0.00 31 92568 3141 0 0
srv_make_money_saldo 887 887 0 0.00 1 18331 332 0 0
srv_recalc_idx_stat 4 4 0 0.00 108508 452562 274094 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4978 4978 0 0.00 1 14823 705 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 2 6450 319 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 3856 29 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1897 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 3098 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1057 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 926 23 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 80062 6013 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2923 17 0 0
t$perf-norm:sp_client_order 20669 20669 0 0.00 0 7896 30 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 11495 24 0 0
t$perf-norm:sp_pay_from_customer 7084 7084 0 0.00 0 9180 19 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 8843 17 0 0
t$perf-norm:sp_reserve_write_off 10106 10106 0 0.00 0 5161 21 0 0
t$perf-norm:sp_supplier_invoice 5592 5592 0 0.00 0 13973 25 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 3019 24 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 2785 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1385 23 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 1391 16 0 0
t$perf-norm:srv_make_money_saldo 887 887 0 0.00 0 1343 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 178 69 0 0
v_add_invoice_to_stock 10130 9850 280 2.76 0 6639 27 0 280
v_all_customers 38192 38192 0 0.00 0 69 3 0 0
v_all_suppliers 30966 30966 0 0.00 0 133 4 0 0
v_all_wares 41320 41320 0 0.00 0 103 3 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2745 14 0 0
v_cancel_client_order 3584 3584 0 0.00 0 3235 10 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 7422 14 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 10642 27 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 1122 10 0 0
v_cancel_supplier_invoice 830 818 12 1.44 0 1621 24 0 12
v_cancel_supplier_order 758 758 0 0.00 0 1396 11 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2721 23 0 0
v_max_id_clo_ord 5483 5483 0 0.00 0 702 4 0 0
v_max_id_clo_res 974 974 0 0.00 0 1144 13 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 4035 7 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 188 6 0 0
v_max_non_paid_realizn 3612 3612 0 0.00 0 6025 10 0 0
v_min_id_clo_ord 5483 5483 0 0.00 0 3067 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 5189 16 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 7532 15 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 5894 15 0 0
v_min_non_paid_realizn 7083 7083 0 0.00 0 4946 14 0 0
v_our_firm 10068 10068 0 0.00 0 54 4 0 0
v_reserve_write_off 20209 20209 0 0.00 0 16657 12 0 0
x_lock_depdocs_on_canc_invoice 10794 10794 0 0.00 0 35449 118 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 12609 374 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 3 44616 1228 0 0
x_make_qstorno_1200_2000 5592 5592 0 0.00 6 95009 2895 0 0
x_make_qstorno_2100_3300 465404 465404 0 0.00 0 63209 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2022 10:38:47 to 03-10-2022 10:38:51.

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
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 25828 8192000000 9711327744 10008285184 788814768 8.783 100 91 180 0 819273328 8465440 21993120
08:09:57 20984 8192000000 9762811712 10072027136 830804160 9.207 100 86 173 0 864575712 13167856 20603696
08:11:22 11317 8192000000 9794995120 10113044480 860287984 9.503 100 85 170 0 902704176 22764208 19651984
08:12:52 8898 8192000000 9869987664 10204631040 909230400 9.990 100 85 165 0 982358608 54817104 18311104
08:14:05 2449 8192000000 9905984800 10245558272 945992048 10.352 100 93 180 0 1020279200 55647728 18639424
08:15:11 1846 8192000000 9971759008 10324647936 998545840 10.864 100 81 159 0 1086660320 71630336 16484144
08:16:16 1598 8192000000 9986738272 10349375488 1025481072 11.125 100 92 178 2 1109968368 66720368 17766928
08:17:20 1747 8192000000 9998849392 10372005888 1037053904 11.236 100 93 175 2 1124098592 69879120 17165568
08:18:24 1114 8192000000 10034283584 10414612480 1058488576 11.442 100 97 185 0 1158620144 80634560 19497008
08:19:26 1247 8192000000 10055938976 10432987136 1065212640 11.506 100 97 185 3 1177158816 92668352 19277824
08:20:28 1159 8192000000 10052018256 10432618496 1074882496 11.599 100 89 170 1 1175483728 84211840 16389392
08:21:30 12151 8192000000 10025235408 10410418176 1073427904 11.585 100 65 126 0 1145735744 59958912 12348928
08:22:43 1258 8192000000 10024571984 10414264320 1087401472 11.718 100 82 149 1 1146917488 44627312 14888704
08:23:45 1107 8192000000 10025742704 10410754048 1094391120 11.784 100 94 183 1 1146405456 34215296 17799040
08:24:47 1111 8192000000 10061004336 10453413888 1095701856 11.797 100 97 176 0 1181821424 69268832 16850736
08:25:49 1156 8192000000 10078788320 10467168256 1097517696 11.814 100 95 180 1 1196984928 80316352 19150880
08:26:51 6964 8192000000 10030664272 10429198336 1092901664 11.770 100 59 112 1 1157497168 54122608 10472896
08:27:59 8955 8192000000 10046627104 10451718144 1099446576 11.832 100 58 114 1 1163038208 52338976 11252656
08:29:08 958 8192000000 10006519392 10389811200 1059011840 11.447 99 92 169 3 1129616848 53069152 17535856
08:30:11 1073 8192000000 10059865568 10450489344 1089742720 11.740 100 95 183 1 1179983568 71554000 18686848
08:31:12 1134 8192000000 10062767904 10448490496 1085100800 11.696 100 94 181 0 1185150288 81600240 18449248
08:32:14 1139 8192000000 10002430560 10384576512 1061029328 11.466 100 94 162 4 1126408752 49961216 15418208
08:33:16 3995 8192000000 9988466256 10358554624 1039344048 11.258 98 83 153 4 1103202000 47870432 15987520
08:34:22 1200 8192000000 9997439376 10369396736 1041106032 11.275 100 94 170 1 1119439856 60524816 17809008
08:35:23 1149 8192000000 9980005184 10354434048 1036698128 11.233 99 80 152 4 1100419200 48695264 15025808
08:36:27 915 8192000000 9993030528 10368032768 1040435664 11.269 100 92 174 1 1115648880 56746176 18467040
08:37:29 10732 8192000000 9986504800 10367668224 1054634464 11.405 100 67 128 2 1111225488 44052480 12538544
08:38:46 8361 8192000000 10034806928 10411974656 1059835392 11.455 100 84 154 3 1146085776 72143952 14106432
08:40:03 4487 8192000000 10026229568 10413838336 1079338256 11.641 100 77 148 0 1145327392 51639504 14349632
08:41:11 7635 8192000000 10078235536 10468634624 1094077280 11.781 100 64 122 2 1188139088 82339856 11721952
08:42:35 12059 8192000000 10133109344 10542436352 1112092480 11.952 100 55 104 0 1247548256 124056432 11399344
08:43:54 1086 8192000000 10177397856 10585292800 1127877360 12.101 100 75 148 0 1284682832 140460400 16345072
08:44:59 2828 8192000000 10216451680 10618458112 1137709056 12.194 100 64 126 2 1331618880 179452640 14457184
08:46:20 2371 8192000000 10212470384 10607026176 1140495952 12.220 100 82 157 1 1325419072 168050336 16872784
08:47:34 1891 8192000000 10299331456 10698104832 1161020848 12.413 100 91 172 2 1412902096 233296288 18584960
08:48:39 1325 8192000000 10323268576 10725228544 1180305024 12.593 100 94 180 2 1435587760 235134928 20147808
08:49:45 1975 8192000000 10367997952 10771329024 1188830176 12.672 100 86 168 0 1482611424 274543952 19237296
08:50:49 1524 8192000000 10408853376 10812977152 1190739952 12.690 100 88 165 0 1520663952 311181600 18742400
08:51:54 1626 8192000000 10463665392 10874998784 1184086560 12.628 99 91 179 0 1572503296 367197648 21219088
08:52:58 1262 8192000000 10434075232 10903740416 1203117152 12.805 100 93 182 0 1543076656 318310448 21649056
08:54:02 797 8192000000 10427803776 10861981696 1207659728 12.847 100 98 192 0 1534482576 304387632 22435216
08:55:06 798 8192000000 10438350288 10846875648 1190142496 12.685 100 91 166 2 1544737840 334546800 20048544
08:56:08 7413 8192000000 10450590240 10881163264 1191335712 12.696 100 53 106 0 1558126880 352967648 13823520
08:57:19 2080 8192000000 10461453216 10914025472 1184888080 12.636 100 51 101 0 1571277392 373006544 13382768
08:58:25 4238 8192000000 10510332112 10957139968 1197619920 12.754 100 60 119 0 1620308976 407612672 15076384
08:59:41 8032 8192000000 10500772752 10946760704 1190695136 12.690 100 46 88 1 1599755296 397247776 11812384
09:00:57 4483 8192000000 10493315936 10972602368 1180914512 12.599 100 50 98 1 1600178320 406794064 12469744
09:02:05 9200 8192000000 10522947824 10965618688 1181183664 12.601 100 58 114 2 1625556000 430395664 13976672
09:03:22 16589 8192000000 10463804080 10907041792 1155395584 12.360 99 43 84 0 1581878256 415309824 11172848
09:04:44 12551 8192000000 10457768608 10923077632 1149728752 12.307 100 30 60 0 1565797872 408053264 8015856
09:06:39 14478 8192000000 10253311056 10792783872 1135864192 12.177 100 22 42 1 1384980016 243595520 5520304
09:07:54 97600 8192000000 10063334320 10507685888 1085670848 11.701 95 5 10 0 1132666384 46138080 857456
09:10:38 105017 8192000000 10075558576 10524950528 1087849232 11.722 95 31 61 1 1148202384 53876048 6477104
09:14:09 77190 8192000000 10098592096 10553450496 1144103680 12.254 100 40 77 0 1190349520 37747520 8498320
09:16:51 19187 8192000000 10100643760 10540343296 1158626368 12.390 100 40 75 0 1204369296 38837744 6905184
09:18:14 84732 8192000000 10110482272 10594676736 1133099280 12.151 99 23 42 1 1195190368 57683072 4408016
09:20:42 27665 8192000000 10107237472 10588553216 1135824128 12.176 99 9 18 0 1206279600 68494336 1961136
09:22:14 77290 8192000000 10158737952 10615554048 1140225744 12.218 99 17 32 1 1233130432 89303424 3601264
09:24:53 54608 8192000000 10124512512 10598424576 1140158704 12.217 99 32 65 0 1284043888 135769104 8116080
09:26:59 36415 8192000000 10109120576 10552967168 1097979424 11.818 96 25 48 0 1175875312 72242816 5653072
09:28:37 44524 8192000000 10123708448 10598219776 1123353376 12.059 98 31 55 0 1221665376 92441840 5870160
09:30:49 49457 8192000000 10166120080 10610769920 1137875072 12.196 99 22 44 0 1245134720 102177024 5082624
09:32:38 26969 8192000000 10125139440 10575822848 1139643936 12.212 100 34 68 0 1227110448 80196800 7269712
09:34:06 70371 8192000000 10165752784 10637844480 1124059968 12.065 98 31 58 1 1226200928 95718896 6422064
09:36:17 89286 8192000000 10074187824 10527318016 1127108576 12.094 99 35 69 0 1177888112 43038224 7741312
09:38:48 38315 8192000000 10107554800 10559807488 1131989376 12.140 99 39 78 1 1212338144 72498352 7850416
09:40:31 35459 8192000000 10079166832 10537697280 1128066672 12.103 99 12 22 0 1199726688 69247824 2412192
09:42:20 45282 8192000000 10073926368 10565033984 1130883152 12.130 99 7 13 0 1165175840 33062832 1229856
09:44:06 86055 8192000000 10051382384 10531332096 1099605728 11.834 97 12 24 0 1150926272 48720784 2599760
09:46:50 79782 8192000000 10128988592 10600517632 1105960208 11.894 98 25 50 2 1208325232 96860160 5504864
09:49:11 60961 8192000000 10059712736 10512859136 1082296144 11.669 96 17 34 0 1154870064 69422672 3151248
09:51:13 54822 8192000000 10036103664 10515656704 1077127920 11.620 96 15 28 0 1142742048 62904384 2709744
09:53:10 4651 8192000000 10065263232 10486263808 1100453120 11.842 99 57 108 1 1153089920 41817536 10819264
09:54:15 21902 8192000000 10167774496 10590605312 1126715424 12.090 100 50 100 2 1268809984 131678432 10416128
09:55:40 29998 8192000000 10209101152 10670866432 1126780256 12.091 99 36 72 0 1301702256 167046192 7875808
09:57:30 48298 8192000000 10083419904 10544164864 1104579808 11.881 99 31 61 0 1172821744 62009552 6232384
09:59:59 36923 8192000000 10085779840 10528030720 1092716352 11.768 97 32 64 0 1158578176 59546192 6315632
10:02:46 61884 8192000000 10058722976 10500255744 1105647488 11.891 98 30 60 0 1156777024 44443712 6685824
10:04:49 30974 8192000000 10036895920 10479407104 1085065904 11.696 96 21 39 1 1105343088 16776592 3500592
10:06:21 50594 8192000000 10056044848 10507681792 1123329248 12.058 100 11 22 0 1148644448 23043520 2271680
10:08:12 100782 8192000000 10113974400 10584596480 1132993520 12.150 99 23 46 0 1198332128 59904592 5434016
10:11:18 72883 8192000000 10065833584 10525200384 1136160208 12.179 100 12 24 0 1190575200 51706848 2708144
10:13:31 33314 8192000000 10087553184 10540249088 1118057472 12.009 99 31 62 0 1198367024 73962832 6346720
10:15:05 39473 8192000000 10051433040 10499117056 1123219696 12.057 100 23 46 1 1153657792 25769392 4668704
10:16:45 22017 8192000000 10056458256 10505150464 1095845776 11.798 99 39 76 1 1165882256 61972016 8064464
10:18:10 26170 8192000000 10119960144 10551324672 1131702416 12.137 100 50 96 0 1189689312 48338480 9648416
10:19:39 40525 8192000000 10140390688 10588106752 1128265168 12.105 100 39 78 0 1224660304 88769568 7625568
10:21:24 47308 8192000000 10145886512 10617454592 1134025232 12.159 99 40 80 1 1246270576 103746304 8499040
10:23:17 41424 8192000000 10111877360 10560016384 1138770896 12.204 100 45 90 0 1199262240 51258592 9232752
10:25:11 48790 8192000000 10077418208 10557538304 1141975648 12.234 100 36 70 0 1191246272 42026176 7244448
10:27:00 63236 8192000000 10057176672 10530172928 1137853760 12.195 100 6 12 0 1175851088 36691568 1305760
10:29:30 47701 8192000000 10091871216 10555060224 1142732192 12.241 99 27 52 0 1193791584 45257648 5801744
10:31:32 19580 8192000000 10109793408 10557493248 1122144976 12.047 99 41 82 1 1192675680 61579760 8950944
10:33:01 21987 8192000000 10133332880 10576314368 1139084208 12.207 100 40 78 0 1229755280 82109872 8561200
10:34:24 50409 8192000000 10072314672 10530029568 1107749568 11.911 98 28 56 0 1159280944 45516800 6014576
10:36:15 49121 8192000000 10119429344 10554527744 1069628896 11.549 95 40 80 0 1185718560 107550848 8538816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-10-2022 10:38:53 to 03-10-2022 10:39:09.

Exceptions occured during test was in run

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

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

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          221500
MON$OLDEST_ACTIVE               226079
MON$OLDEST_SNAPSHOT             226079
MON$NEXT_TRANSACTION            226079
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                       2023424
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Mon Oct  3 10:37:30 2022
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Mon Oct  3 10:37:33 2022
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Mon Oct  3 10:37:33 2022
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
--- end of diff output ---

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

03.10.22 10:39:14. 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-10-03 10:39:16 No severe PSQL-related problems occured
03.10.22 10:39:18. End of report.