Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.03.2023 07:39
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 14870896 kB
MemAvailable 30437932 kB
Buffers 0 kB
Cached 16853964 kB
SwapCached 3028 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 2023-03-06 07:37:27
DTS_END                         2023-03-06 07:37:27.9870
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/45958
TRN_ID                          224085
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: 9199.73 29007 1103967
customer order: creation 165.93 434 19912
customer order: refuse 14.36 362 1723
order to supplier: creation 46.51 1263 5581
order to supplier: removal 3.46 164793 415
invoice (draft): creation 47.41 2903 5689
invoice (draft): removal 50.90 925 6108
invoice accept: apply 42.60 23088 5112
invoice accept: cancel 45.52 1201 5462
customer reserve: creation 3976.32 218 477158
customer reserve: removal 4520.32 102 542438
realization accept: apply 81.51 239 9781
realization accept: cancel 78.25 164 9390
payment from customer: creation 57.56 165 6907
payment from customer: removal 5.60 104 672
payment to supplier: creation 48.25 171 5790
payment to supplier: removal 3.42 116 410
service: total inventory turnovers 3.93 4042 472
service: total monetary turnovers 6.91 374 829
service: aggregate perf. data 0.95 14860 114
service: refresh index statistics 0.03 364610 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-03-2023 07:40:00 to 06-03-2023 07:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7468
TEST_TIME 2 5810
TEST_TIME 3 6143
TEST_TIME 4 5293
TEST_TIME 5 5564
TEST_TIME 6 5544
TEST_TIME 7 5552
TEST_TIME 8 5866
TEST_TIME 9 6153
TEST_TIME 10 6446
TEST_TIME 11 6664
TEST_TIME 12 6871
TEST_TIME 13 6972
TEST_TIME 14 7107
TEST_TIME 15 7347
TEST_TIME 16 7392
TEST_TIME 17 7444
TEST_TIME 18 7517
TEST_TIME 19 7617
TEST_TIME 20 7804
TEST_TIME 21 7957
TEST_TIME 22 7916
TEST_TIME 23 8112
TEST_TIME 24 8577
TEST_TIME 25 9093
TEST_TIME 26 8996
TEST_TIME 27 9119
TEST_TIME 28 9380
TEST_TIME 29 9302
TEST_TIME 30 10140
TEST_TIME 31 10230
TEST_TIME 32 10087
TEST_TIME 33 10185
TEST_TIME 34 10752
TEST_TIME 35 10818
TEST_TIME 36 10738
TEST_TIME 37 10626
TEST_TIME 38 10483
TEST_TIME 39 10406
TEST_TIME 40 10484
TEST_TIME 41 10401
TEST_TIME 42 10346
TEST_TIME 43 10391
TEST_TIME 44 10445
TEST_TIME 45 10436
TEST_TIME 46 10268
TEST_TIME 47 10200
TEST_TIME 48 10038
TEST_TIME 49 10006
TEST_TIME 50 9899
TEST_TIME 51 9862
TEST_TIME 52 9791
TEST_TIME 53 9729
TEST_TIME 54 9862
TEST_TIME 55 9861
TEST_TIME 56 9844
TEST_TIME 57 9805
TEST_TIME 58 9705
TEST_TIME 59 9605
TEST_TIME 60 9523
TEST_TIME 61 9513
TEST_TIME 62 9475
TEST_TIME 63 9433
TEST_TIME 64 9396
TEST_TIME 65 9396
TEST_TIME 66 9366
TEST_TIME 67 9369
TEST_TIME 68 9335
TEST_TIME 69 9398
TEST_TIME 70 9489
TEST_TIME 71 9530
TEST_TIME 72 9518
TEST_TIME 73 9566
TEST_TIME 74 9564
TEST_TIME 75 9591
TEST_TIME 76 9592
TEST_TIME 77 9603
TEST_TIME 78 9530
TEST_TIME 79 9466
TEST_TIME 80 9442
TEST_TIME 81 9387
TEST_TIME 82 9379
TEST_TIME 83 9341
TEST_TIME 84 9288
TEST_TIME 85 9212
TEST_TIME 86 9204
TEST_TIME 87 9179
TEST_TIME 88 9137
TEST_TIME 89 9169
TEST_TIME 90 9168
TEST_TIME 91 9124
TEST_TIME 92 9138
TEST_TIME 93 9272
TEST_TIME 94 9298
TEST_TIME 95 9314
TEST_TIME 96 9286
TEST_TIME 97 9271
TEST_TIME 98 9260
TEST_TIME 99 9204
TEST_TIME 100 9203
TEST_TIME 101 9160
TEST_TIME 102 9152
TEST_TIME 103 9121
TEST_TIME 104 9092
TEST_TIME 105 9076
TEST_TIME 106 9056
TEST_TIME 107 9067
TEST_TIME 108 9056
TEST_TIME 109 9075
TEST_TIME 110 9053
TEST_TIME 111 9067
TEST_TIME 112 9106
TEST_TIME 113 9065
TEST_TIME 114 9145
TEST_TIME 115 9107
TEST_TIME 116 9217
TEST_TIME 117 9380
TEST_TIME 118 9331
TEST_TIME 119 9298
TEST_TIME 120 9228

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-03-2023 07:40:04 to 06-03-2023 07:40:07.

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 1541983 1541983 0 0.00 0 29000 11 0 0
doc_list_biud 1541969 1541969 0 0.00 0 37960 41 0 0
sp_add_invoice_to_stock 5249 5112 137 2.61 65 185940 23089 0 137
sp_cancel_adding_invoice 5462 5462 0 0.00 5 99885 1202 0 0
sp_cancel_client_order 1723 1723 0 0.00 5 10734 362 0 0
sp_cancel_customer_reserve 542438 542438 0 0.00 1 37962 102 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 4 5667 104 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3562 117 0 0
sp_cancel_supplier_invoice 6117 6108 9 0.14 2 27061 925 0 9
sp_cancel_supplier_order 415 415 0 0.00 6 1302414 164794 0 0
sp_cancel_write_off 9390 9390 0 0.00 2 6271 165 0 0
sp_client_order 19912 19912 0 0.00 5 34171 435 0 0
sp_customer_reserve 477158 477158 0 0.00 0 92622 218 0 0
sp_fill_shopping_cart 31177 31177 0 0.00 1 9178 75 0 0
sp_fill_shopping_cart_clo_res 477124 477124 0 0.00 0 14807 17 0 0
sp_get_clo_for_invoice 5067 5067 0 0.00 0 26444 219 0 0
sp_kill_cost_storno 15934 15934 0 0.00 0 7749 68 0 0
sp_kill_qstorno_ret_qs2qd 1100789 1100789 0 0.00 0 37957 45 0 0
sp_kill_qty_storno 580382 580382 0 0.00 0 37960 102 0 0
sp_lock_selected_doc 20566 20566 0 0.00 0 7186 6 0 0
sp_make_cost_storno 27544 27544 0 0.00 0 28985 70 0 0
sp_multiply_rows_for_pdistr 27544 27544 0 0.00 0 6825 31 0 0
sp_multiply_rows_for_qdistr 19910 19910 0 0.00 0 16892 181 0 0
sp_pay_from_customer 6907 6907 0 0.00 5 16592 165 0 0
sp_pay_to_supplier 5790 5790 0 0.00 6 15727 172 0 0
sp_payment_common 12697 12697 0 0.00 2 9865 39 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 10727 301 0 0
sp_qd_handle_on_invoice_upd_sts 10527 10527 0 0.00 0 18413 485 0 0
sp_qd_handle_on_reserve_upd_sts 9781 9781 0 0.00 0 24239 57 0 0
sp_reserve_write_off 9781 9781 0 0.00 8 24407 240 0 0
sp_supplier_invoice 5689 5689 0 0.00 7 89757 2904 0 0
sp_supplier_order 5581 5581 0 0.00 7 34772 1263 0 0
srv_aggregate_perf_data 114 114 0 0.00 2998 198019 14861 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 877 140977 35566 0 0
srv_find_qd_qs_mism 1054303 1054303 0 0.00 0 17668 9 0 0
srv_make_invnt_saldo 472 472 0 0.00 46 104013 4042 0 0
srv_make_money_saldo 829 829 0 0.00 1 21821 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 256684 493821 364611 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 1 9932 768 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 2 5564 413 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 7077 31 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 2097 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 558 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 871 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1873 29 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 75245 6536 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1832 18 0 0
t$perf-norm:sp_client_order 19913 19913 0 0.00 0 4568 31 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 824 13 0 0
t$perf-norm:sp_pay_from_customer 6907 6907 0 0.00 0 2506 16 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 6968 17 0 0
t$perf-norm:sp_reserve_write_off 9781 9781 0 0.00 0 9332 21 0 0
t$perf-norm:sp_supplier_invoice 5689 5689 0 0.00 0 5300 26 0 0
t$perf-norm:sp_supplier_order 5581 5581 0 0.00 0 1714 26 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 327 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 91 4 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 3731 19 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 612 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 119 38 0 0
v_add_invoice_to_stock 10402 10128 274 2.63 0 6261 25 0 274
v_all_customers 36590 36590 0 0.00 0 143 3 0 0
v_all_suppliers 31144 31144 0 0.00 0 110 4 0 0
v_all_wares 39820 39820 0 0.00 0 71 3 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2769 18 0 0
v_cancel_client_order 3446 3446 0 0.00 0 2821 11 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 5592 16 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1231 11 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3523 17 0 0
v_cancel_supplier_invoice 866 848 18 2.07 0 7099 33 0 18
v_cancel_supplier_order 830 830 0 0.00 0 3445 22 0 0
v_cancel_write_off 1970 1970 0 0.00 0 3138 24 0 0
v_max_id_clo_ord 5581 5581 0 0.00 0 2612 6 0 0
v_max_id_clo_res 968 968 0 0.00 0 1762 16 0 0
v_max_id_ord_sup 5686 5686 0 0.00 0 3753 5 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 1151 7 0 0
v_max_non_paid_realizn 3481 3481 0 0.00 0 1414 7 0 0
v_min_id_clo_ord 5581 5581 0 0.00 0 4264 7 0 0
v_min_id_clo_res 968 968 0 0.00 0 6334 26 0 0
v_min_id_ord_sup 5686 5686 0 0.00 0 5467 11 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 4991 12 0 0
v_min_non_paid_realizn 6907 6907 0 0.00 0 6396 14 0 0
v_our_firm 10082 10082 0 0.00 0 61 4 0 0
v_reserve_write_off 19562 19562 0 0.00 0 7618 12 0 0
x_lock_depdocs_on_canc_invoice 11146 11146 0 0.00 0 20674 126 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 16007 338 0 0
x_make_qstorno_1000_1200 5581 5581 0 0.00 2 34673 1102 0 0
x_make_qstorno_1200_2000 5689 5689 0 0.00 3 88174 2711 0 0
x_make_qstorno_2100_3300 477156 477156 0 0.00 0 92619 192 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-03-2023 07:40:08 to 06-03-2023 07:40:13.

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:32 12 8192000000 8820566560 8840605696 19183856 0.233 9 5 6 0 19534576 212560 138160
05:08:32 28053 8192000000 9707566688 10004172800 786479648 8.759 100 84 168 0 815820912 8018928 21322336
05:10:11 20551 8192000000 9759159344 10066055168 832494592 9.224 100 88 175 0 866714800 13051776 21168432
05:11:35 19198 8192000000 9809508336 10128420864 870870544 9.609 100 77 150 0 911808336 24026176 16911616
05:12:56 3382 8192000000 9876233152 10211004416 925733776 10.153 100 89 170 1 984609360 40470944 18404640
05:14:05 4028 8192000000 9922972448 10268553216 966290352 10.550 100 92 176 3 1042463488 58433136 17740000
05:15:12 1707 8192000000 9980027312 10333712384 995414544 10.834 100 93 181 0 1089772496 76029408 18328544
05:16:16 1252 8192000000 9984526016 10346684416 1016426128 11.038 100 94 186 3 1100350336 65545312 18378896
05:17:19 912 8192000000 10017753024 10386874368 1038132848 11.247 100 97 187 1 1140570560 83835968 18601744
05:18:21 1105 8192000000 10059417456 10434514944 1056656288 11.424 100 97 190 1 1178789680 102744720 19388672
05:19:23 1074 8192000000 10031052464 10409484288 1066769280 11.521 100 95 186 1 1151523616 66122976 18631360
05:20:26 978 8192000000 10053993792 10441715712 1076257536 11.612 100 90 173 0 1177020848 82861216 17902096
05:21:27 1330 8192000000 10016380480 10405548032 1084061600 11.686 100 87 166 3 1145652368 44563728 17027040
05:22:29 1181 8192000000 10076825616 10470510592 1089617744 11.739 100 89 172 1 1202788768 95133264 18037760
05:23:31 1223 8192000000 10080930080 10469015552 1092202672 11.764 100 98 194 2 1203416240 91965200 19248368
05:24:34 1387 8192000000 10079739088 10466734080 1092837728 11.770 100 98 182 1 1198615456 87366288 18411440
05:25:36 895 8192000000 10077751968 10468855808 1097921008 11.818 100 91 174 0 1201229120 86071232 17236880
05:26:38 1217 8192000000 10066171760 10460987392 1095960208 11.799 100 96 187 1 1187962288 72441280 19560800
05:27:40 4744 8192000000 10048374208 10442547200 1092103264 11.763 100 66 127 0 1172906896 67614752 13188880
05:28:52 1421 8192000000 10049394288 10436907008 1096899776 11.808 100 83 161 2 1170890848 56886416 17104656
05:29:55 1085 8192000000 10069845376 10460958720 1101352912 11.850 100 81 152 2 1190367136 73542080 15472144
05:30:56 2281 8192000000 10011383808 10398756864 1076158640 11.611 100 76 145 1 1149253440 57371616 15723184
05:32:00 1049 8192000000 10045909072 10426884096 1071678000 11.568 99 92 166 2 1171932544 82522352 17732192
05:33:03 940 8192000000 9979461792 10354556928 1028771872 11.157 100 87 161 0 1107119584 61355024 16992688
05:34:04 1624 8192000000 9990092752 10364674048 1044765056 11.310 100 81 151 0 1111876928 51905184 15206688
05:35:06 2719 8192000000 9976677248 10353659904 1030406864 11.172 99 62 121 0 1095707152 53445984 11854304
05:36:09 1213 8192000000 9998884144 10374926336 1044496976 11.308 100 95 178 2 1122627280 59143440 18986864
05:37:12 1008 8192000000 9999365824 10371514368 1036355792 11.230 99 94 175 2 1120656128 65962592 18337744
05:38:18 3258 8192000000 10003999072 10379866112 1051856304 11.378 100 78 152 0 1123707552 56081264 15769984
05:39:26 7288 8192000000 10058299632 10446647296 1074256944 11.593 100 79 144 2 1174857056 85202272 15397840
05:40:40 3076 8192000000 10057528304 10443792384 1086083728 11.705 100 74 144 2 1174313344 72706080 15523536
05:41:47 2595 8192000000 10138573616 10525364224 1096917664 11.808 100 75 145 1 1253644752 141466080 15261008
05:42:56 6896 8192000000 10113456832 10514370560 1112301712 11.954 100 61 112 0 1229524448 104733376 12489360
05:44:09 8507 8192000000 10189679792 10586337280 1117580800 12.004 100 77 151 2 1304639136 170274192 16784144
05:45:21 2049 8192000000 10158760752 10576515072 1127388416 12.097 100 71 132 1 1281509648 138478512 15642720
05:46:28 2160 8192000000 10200192432 10602610688 1132397248 12.144 100 89 168 0 1317192864 166042432 18753184
05:47:34 864 8192000000 10242898128 10635587584 1148493136 12.295 100 92 177 0 1354460592 186251616 19715840
05:48:37 1049 8192000000 10283881632 10688528384 1163828000 12.439 100 88 170 1 1395770304 212490704 19451600
05:49:46 4629 8192000000 10299558208 10724216832 1175226848 12.546 100 86 170 2 1410474864 216102208 19145808
05:50:55 1132 8192000000 10340696016 10750382080 1189857648 12.682 100 92 180 0 1451590144 241505888 20226608
05:51:59 1555 8192000000 10333178896 10755276800 1175753728 12.551 100 91 177 1 1447757104 252431504 19571872
05:53:04 3279 8192000000 10356788240 10775912448 1192270272 12.704 100 76 135 1 1465037648 257522544 15244832
05:54:17 11956 8192000000 10361160064 10782806016 1175857504 12.552 99 57 110 1 1465458608 276030352 13570752
05:55:31 3937 8192000000 10394835872 10825785344 1185047248 12.637 100 58 104 2 1503727344 306062640 12617456
05:56:42 1899 8192000000 10457627952 10885103616 1194601104 12.726 100 73 137 1 1564810032 354084720 16124208
05:57:46 2476 8192000000 10443418624 10855849984 1183843392 12.626 100 79 150 3 1546947120 344255952 18847776
05:58:52 2081 8192000000 10462163248 10903699456 1183492880 12.623 100 76 145 1 1571090656 369097168 18500608
06:00:01 2164 8192000000 10457344304 10909790208 1178167248 12.573 100 75 145 0 1572734128 377045568 17521312
06:01:06 982 8192000000 10443442752 10904690688 1190716784 12.690 100 88 164 2 1549966736 340094512 19155440
06:02:08 6324 8192000000 10375123712 10831618048 1181945728 12.608 100 42 82 0 1497488048 305268848 10273472
06:03:29 40272 8192000000 10417479568 10885103616 1161901568 12.421 98 29 58 0 1500886656 331515120 7469968
06:05:27 8236 8192000000 10343129728 10804248576 1145250880 12.265 100 29 58 1 1450499072 297534624 7713568
06:06:37 2303 8192000000 10377936336 10827743232 1158559824 12.390 100 58 111 1 1482250112 310984592 12705696
06:07:42 9330 8192000000 10226214880 10759393280 1100120208 11.839 96 47 94 0 1329513392 216894304 12498880
06:08:55 17066 8192000000 10215844608 10633490432 1128853952 12.111 100 48 90 0 1290216832 152116992 9245888
06:10:15 27312 8192000000 10236775216 10732072960 1129435504 12.116 100 18 36 0 1333873520 199706560 4731456
06:11:50 107734 8192000000 10057580800 10525339648 1082412928 11.670 96 14 28 0 1132972880 47622480 2937472
06:14:39 27936 8192000000 10056236000 10480226304 1114502448 11.975 99 44 87 0 1143501232 19212688 9786096
06:16:17 140825 8192000000 10054177568 10546769920 1083634112 11.682 96 14 28 0 1134767104 49095840 2037152
06:19:40 102682 8192000000 10105532144 10598604800 1129334592 12.115 99 13 26 0 1198327904 65926128 3067184
06:22:27 81718 8192000000 10108977616 10562551808 1138607184 12.202 100 23 46 0 1182506880 39242960 4656736
06:25:09 16064 8192000000 10090018752 10511310848 1128109312 12.104 99 55 105 3 1160033024 20891392 11032320
06:26:29 77074 8192000000 10119280896 10558459904 1125404720 12.078 98 30 60 0 1209840208 78488320 5947168
06:28:48 61275 8192000000 10174830208 10621394944 1094014704 11.781 95 25 49 0 1198634960 99099344 5520912
06:30:53 33508 8192000000 10184248544 10668601344 1129598656 12.118 99 32 59 0 1270266960 133985600 6682704
06:32:32 40467 8192000000 10081938080 10526408704 1106813408 11.902 97 26 52 1 1159991568 47802464 5375696
06:34:15 39089 8192000000 10055475760 10542723072 1123952352 12.064 99 6 12 0 1159944352 34748256 1243744
06:36:28 61443 8192000000 10067226624 10506596352 1123435600 12.059 99 18 34 1 1177062064 50168672 3457792
06:38:29 76480 8192000000 10068314688 10552299520 1122041328 12.046 99 8 16 0 1173168416 49334400 1792688
06:41:08 35229 8192000000 10077522496 10533175296 1119145072 12.019 98 32 64 0 1164295744 38842160 6308512
06:42:44 30591 8192000000 10110112352 10563018752 1142810400 12.242 100 23 46 0 1203206848 54753088 5643360
06:44:18 25597 8192000000 10155770400 10625216512 1143089424 12.245 100 42 84 0 1276003216 123669264 9244528
06:45:48 56049 8192000000 10146943040 10614157312 1103669984 11.872 97 23 44 0 1216428848 107612384 5146480
06:47:48 102339 8192000000 10081881616 10523430912 1118754688 12.015 98 18 36 0 1156002928 33756208 3492032
06:50:32 64633 8192000000 10087709696 10540441600 1096590720 11.805 96 21 41 0 1140077040 39260272 4226048
06:52:43 51741 8192000000 10099127536 10551586816 1113124480 11.962 98 34 68 0 1162610016 41818048 7667488
06:54:42 117871 8192000000 10078248000 10532982784 1071681136 11.568 95 31 61 0 1127412656 49071296 6660224
06:57:53 14854 8192000000 10043896480 10473275392 1105360736 11.888 99 45 85 1 1149548016 35526288 8660992
06:59:09 22701 8192000000 10074069904 10514350080 1095311056 11.793 98 46 90 2 1153163520 48200608 9651856
07:00:34 59879 8192000000 10035394992 10499493888 1107202160 11.906 99 7 14 0 1130257232 21647072 1408000
07:02:34 78240 8192000000 10052644064 10525749248 1075500480 11.605 96 15 30 1 1129122336 50524624 3097232
07:04:53 25380 8192000000 10108230272 10567016448 1128249584 12.105 100 33 65 0 1200051184 64919360 6882240
07:06:19 42298 8192000000 10144751312 10609197056 1140586656 12.221 100 31 62 0 1240297760 92296640 7414464
07:08:01 55663 8192000000 10166893408 10606198784 1101312592 11.850 96 27 54 3 1224692464 117508624 5871248
07:10:00 58649 8192000000 10111423104 10621603840 1126367600 12.087 100 36 72 0 1287360848 153471968 7521280
07:11:59 86985 8192000000 10074942112 10508222464 1094286912 11.783 97 43 77 4 1127681616 25570720 7823984
07:14:30 14316 8192000000 10125490464 10545627136 1115494944 11.984 100 69 132 1 1213663792 84522112 13646736
07:15:48 93922 8192000000 10094801952 10545147904 1126595568 12.089 100 25 50 0 1227189168 95440032 5153568
07:18:23 56207 8192000000 10079278160 10576510976 1128712656 12.109 100 8 16 0 1182889760 52677328 1499776
07:20:19 68741 8192000000 10077983072 10523144192 1105998848 11.895 98 15 30 0 1141865664 32370288 3496528
07:22:42 16760 8192000000 10138650144 10573025280 1144637984 12.259 100 35 70 0 1238105136 85013488 8453664
07:24:02 15098 8192000000 10175220864 10617798656 1151480640 12.323 100 57 111 0 1268865840 104957728 12427472
07:25:27 25443 8192000000 10207213552 10646945792 1162140928 12.423 100 32 64 1 1292483840 123317808 7025104
07:26:54 32798 8192000000 10181163808 10632974336 1146044992 12.272 99 27 54 0 1283473248 130970208 6458048
07:28:37 30170 8192000000 10248961232 10723295232 1167258560 12.471 100 49 98 0 1333626848 155394608 10973680
07:30:22 31271 8192000000 10259411792 10698616832 1122946016 12.055 96 30 58 0 1322152864 191953712 7253136
07:31:59 34040 8192000000 10262977504 10726752256 1159732720 12.401 100 29 56 0 1368051120 201388496 6929904
07:33:33 33523 8192000000 10235679808 10714767360 1139491840 12.211 99 24 48 0 1347826352 202285440 6049072
07:35:07 92858 8192000000 10092442656 10596098048 1133478480 12.154 99 13 26 0 1173915664 37677760 2759424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 06-03-2023 07:40:14 to 06-03-2023 07:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-03-2023 07:40:32 to 06-03-2023 07:40:34.

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          223506
MON$OLDEST_ACTIVE               224243
MON$OLDEST_SNAPSHOT             224243
MON$NEXT_TRANSACTION            224243
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                       2085920
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Mon Mar  6 07:37:29 2023
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
--- end of diff output ---

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

06.03.23 07:40:35. 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
2023-03-06 07:40:38 No severe PSQL-related problems occured
06.03.23 07:40:39. End of report.