Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2023 13: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 14777244 kB
MemAvailable 30429160 kB
Buffers 0 kB
Cached 16942148 kB
SwapCached 4452 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-01-11 13:37:27
DTS_END                         2023-01-11 13:37:27.7500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39612
TRN_ID                          228096
ATT_ID                          634
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: 9241.70 30648 1109004
customer order: creation 169.43 502 20331
customer order: refuse 15.19 401 1823
order to supplier: creation 46.31 1441 5557
order to supplier: removal 3.47 158995 416
invoice (draft): creation 46.03 3077 5523
invoice (draft): removal 52.71 938 6325
invoice accept: apply 42.50 24974 5100
invoice accept: cancel 47.84 1232 5741
customer reserve: creation 3824.08 244 458889
customer reserve: removal 4696.13 93 563535
realization accept: apply 83.06 288 9967
realization accept: cancel 84.33 156 10120
payment from customer: creation 59.85 192 7182
payment from customer: removal 5.83 140 700
payment to supplier: creation 49.49 211 5939
payment to supplier: removal 3.50 119 420
service: total inventory turnovers 3.81 4298 457
service: total monetary turnovers 7.27 405 872
service: aggregate perf. data 0.86 19001 103
service: refresh index statistics 0.03 396244 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2023 13:39:59 to 11-01-2023 13:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9142
TEST_TIME 2 5941
TEST_TIME 3 5859
TEST_TIME 4 5860
TEST_TIME 5 5591
TEST_TIME 6 5808
TEST_TIME 7 5995
TEST_TIME 8 6170
TEST_TIME 9 6813
TEST_TIME 10 6888
TEST_TIME 11 7036
TEST_TIME 12 7267
TEST_TIME 13 7275
TEST_TIME 14 7550
TEST_TIME 15 7452
TEST_TIME 16 7605
TEST_TIME 17 7644
TEST_TIME 18 7941
TEST_TIME 19 8029
TEST_TIME 20 7971
TEST_TIME 21 7981
TEST_TIME 22 8097
TEST_TIME 23 8214
TEST_TIME 24 8477
TEST_TIME 25 8790
TEST_TIME 26 8957
TEST_TIME 27 8949
TEST_TIME 28 8929
TEST_TIME 29 8920
TEST_TIME 30 9070
TEST_TIME 31 9287
TEST_TIME 32 9315
TEST_TIME 33 10229
TEST_TIME 34 9993
TEST_TIME 35 10211
TEST_TIME 36 10361
TEST_TIME 37 10748
TEST_TIME 38 10688
TEST_TIME 39 10523
TEST_TIME 40 10363
TEST_TIME 41 10303
TEST_TIME 42 10142
TEST_TIME 43 10047
TEST_TIME 44 10001
TEST_TIME 45 10043
TEST_TIME 46 10381
TEST_TIME 47 10404
TEST_TIME 48 10353
TEST_TIME 49 10228
TEST_TIME 50 10114
TEST_TIME 51 9998
TEST_TIME 52 9883
TEST_TIME 53 9914
TEST_TIME 54 9822
TEST_TIME 55 9721
TEST_TIME 56 9720
TEST_TIME 57 9679
TEST_TIME 58 9605
TEST_TIME 59 9688
TEST_TIME 60 9709
TEST_TIME 61 9679
TEST_TIME 62 9587
TEST_TIME 63 9517
TEST_TIME 64 9519
TEST_TIME 65 9615
TEST_TIME 66 9590
TEST_TIME 67 9549
TEST_TIME 68 9571
TEST_TIME 69 9566
TEST_TIME 70 9608
TEST_TIME 71 9678
TEST_TIME 72 9740
TEST_TIME 73 9670
TEST_TIME 74 9670
TEST_TIME 75 9786
TEST_TIME 76 9751
TEST_TIME 77 9662
TEST_TIME 78 9579
TEST_TIME 79 9499
TEST_TIME 80 9440
TEST_TIME 81 9418
TEST_TIME 82 9389
TEST_TIME 83 9399
TEST_TIME 84 9318
TEST_TIME 85 9254
TEST_TIME 86 9261
TEST_TIME 87 9200
TEST_TIME 88 9379
TEST_TIME 89 9368
TEST_TIME 90 9364
TEST_TIME 91 9337
TEST_TIME 92 9294
TEST_TIME 93 9309
TEST_TIME 94 9364
TEST_TIME 95 9409
TEST_TIME 96 9414
TEST_TIME 97 9416
TEST_TIME 98 9440
TEST_TIME 99 9408
TEST_TIME 100 9410
TEST_TIME 101 9323
TEST_TIME 102 9302
TEST_TIME 103 9361
TEST_TIME 104 9302
TEST_TIME 105 9265
TEST_TIME 106 9227
TEST_TIME 107 9226
TEST_TIME 108 9210
TEST_TIME 109 9205
TEST_TIME 110 9205
TEST_TIME 111 9207
TEST_TIME 112 9177
TEST_TIME 113 9206
TEST_TIME 114 9212
TEST_TIME 115 9251
TEST_TIME 116 9234
TEST_TIME 117 9251
TEST_TIME 118 9279
TEST_TIME 119 9308
TEST_TIME 120 9262

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2023 13:40:03 to 11-01-2023 13:40:06.

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 1533174 1533174 0 0.00 0 36271 13 0 0
doc_list_biud 1533166 1533166 0 0.00 0 46078 41 0 0
sp_add_invoice_to_stock 5214 5100 114 2.18 34 201159 24975 0 114
sp_cancel_adding_invoice 5741 5741 0 0.00 7 91727 1233 0 0
sp_cancel_client_order 1823 1823 0 0.00 6 12032 402 0 0
sp_cancel_customer_reserve 563535 563535 0 0.00 1 46080 93 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 7773 140 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 4 3620 119 0 0
sp_cancel_supplier_invoice 6339 6325 14 0.22 3 18911 939 0 14
sp_cancel_supplier_order 416 416 0 0.00 12 1357889 158995 0 0
sp_cancel_write_off 10120 10120 0 0.00 2 17716 156 0 0
sp_client_order 20331 20331 0 0.00 6 37449 503 0 0
sp_customer_reserve 458889 458889 0 0.00 1 119357 245 0 0
sp_fill_shopping_cart 31403 31403 0 0.00 1 11133 86 0 0
sp_fill_shopping_cart_clo_res 458859 458859 0 0.00 0 19277 19 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 32858 284 0 0
sp_kill_cost_storno 16981 16981 0 0.00 0 13030 69 0 0
sp_kill_qstorno_ret_qs2qd 1143931 1143931 0 0.00 0 46073 41 0 0
sp_kill_qty_storno 602988 602988 0 0.00 0 46077 95 0 0
sp_lock_selected_doc 20947 20947 0 0.00 0 10170 8 0 0
sp_make_cost_storno 28151 28151 0 0.00 0 20494 80 0 0
sp_multiply_rows_for_pdistr 28151 28151 0 0.00 0 14327 40 0 0
sp_multiply_rows_for_qdistr 20331 20331 0 0.00 0 18963 190 0 0
sp_pay_from_customer 7182 7182 0 0.00 5 20667 193 0 0
sp_pay_to_supplier 5939 5939 0 0.00 7 21291 212 0 0
sp_payment_common 13120 13120 0 0.00 2 15812 48 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 1 12002 322 0 0
sp_qd_handle_on_invoice_upd_sts 10803 10803 0 0.00 0 27187 534 0 0
sp_qd_handle_on_reserve_upd_sts 9966 9966 0 0.00 0 19448 66 0 0
sp_reserve_write_off 9967 9967 0 0.00 8 27294 289 0 0
sp_supplier_invoice 5523 5523 0 0.00 12 109112 3078 0 0
sp_supplier_order 5557 5557 0 0.00 8 43116 1441 0 0
srv_aggregate_perf_data 103 103 0 0.00 2644 237468 19002 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 917 156810 34864 0 0
srv_find_qd_qs_mism 1060968 1060968 0 0.00 0 36264 10 0 0
srv_make_invnt_saldo 457 457 0 0.00 55 126027 4298 0 0
srv_make_money_saldo 872 872 0 0.00 1 18150 405 0 0
srv_recalc_idx_stat 4 4 0 0.00 222234 497674 396245 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 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 5105 5105 0 0.00 1 12031 925 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 2 7356 458 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 2547 27 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 2507 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1294 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2091 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1169 42 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 83362 6367 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1252 19 0 0
t$perf-norm:sp_client_order 20331 20331 0 0.00 0 8165 39 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1208 14 0 0
t$perf-norm:sp_pay_from_customer 7182 7182 0 0.00 0 8831 19 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 23323 23 0 0
t$perf-norm:sp_reserve_write_off 9968 9968 0 0.00 0 3591 21 0 0
t$perf-norm:sp_supplier_invoice 5524 5524 0 0.00 0 1575 29 0 0
t$perf-norm:sp_supplier_order 5557 5557 0 0.00 0 5190 33 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 839 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 35 5 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 1031 14 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 287 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 405 105 0 0
v_add_invoice_to_stock 10348 10120 228 2.20 0 4485 29 0 228
v_all_customers 37502 37502 0 0.00 0 104 4 0 0
v_all_suppliers 30772 30772 0 0.00 0 101 4 0 0
v_all_wares 40660 40660 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1177 18 0 0
v_cancel_client_order 3646 3646 0 0.00 0 5304 15 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1921 14 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 1 3207 15 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 3578 23 0 0
v_cancel_supplier_invoice 826 798 28 3.38 0 989 28 0 28
v_cancel_supplier_order 832 832 0 0.00 0 1333 11 0 0
v_cancel_write_off 2022 2022 0 0.00 0 9609 31 0 0
v_max_id_clo_ord 5553 5553 0 0.00 0 2204 6 0 0
v_max_id_clo_res 984 984 0 0.00 0 2479 16 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 5900 10 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 171 8 0 0
v_max_non_paid_realizn 3772 3772 0 0.00 0 1308 8 0 0
v_min_id_clo_ord 5553 5553 0 0.00 0 2245 11 0 0
v_min_id_clo_res 984 984 0 0.00 0 2331 12 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 8323 12 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 8384 17 0 0
v_min_non_paid_realizn 7182 7182 0 0.00 0 5193 17 0 0
v_our_firm 9978 9978 0 0.00 0 56 5 0 0
v_reserve_write_off 19930 19930 0 0.00 0 5734 14 0 0
x_lock_depdocs_on_canc_invoice 11667 11667 0 0.00 0 10704 117 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 8584 346 0 0
x_make_qstorno_1000_1200 5557 5557 0 0.00 2 43057 1265 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 5 107681 2843 0 0
x_make_qstorno_2100_3300 458886 458886 0 0.00 0 119347 213 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-01-2023 13:40:07 to 11-01-2023 13:40:12.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:27 3 8192000000 8798450992 8809598976 4474144 0.054 2 1 2 0 4573568 63648 35776
11:08:27 19940 8192000000 9721025088 10019528704 800140192 8.898 100 88 174 1 829340688 7969840 21230656
11:09:52 18190 8192000000 9765243152 10071420928 831117904 9.210 100 87 174 0 863038656 11641808 20278944
11:11:16 15476 8192000000 9789323776 10110181376 864237536 9.543 100 82 159 0 900480320 18055168 18187616
11:12:35 6805 8192000000 9861122544 10190589952 908225104 9.980 100 83 164 1 969470064 43244048 18000912
11:13:45 2613 8192000000 9918000144 10259353600 953725872 10.428 100 89 178 0 1035126768 63118960 18281936
11:14:50 1793 8192000000 9972478336 10328027136 990544384 10.787 100 91 178 0 1090907952 81842576 18520992
11:15:55 1589 8192000000 10000094048 10364366848 1025299712 11.123 100 92 175 1 1128459728 85118224 18041792
11:16:58 1279 8192000000 10000913664 10368655360 1046387904 11.326 100 89 158 1 1116124688 55473648 14263136
11:18:01 736 8192000000 10048978128 10425585664 1069955808 11.552 100 97 191 1 1165352720 75279296 20117616
11:19:02 1335 8192000000 10056085136 10441216000 1076117920 11.610 100 79 154 1 1178035440 85761840 16155680
11:20:05 1201 8192000000 10046864256 10432413696 1084083696 11.686 100 89 172 1 1173855120 73254400 16517024
11:21:07 2114 8192000000 10044381424 10427236352 1086098048 11.706 100 78 133 1 1158253952 58931504 13224400
11:22:10 6698 8192000000 10026700848 10417369088 1091872528 11.760 100 79 154 1 1161508752 54269136 15367088
11:23:17 1135 8192000000 10064807616 10455457792 1096466624 11.804 100 95 186 1 1185722240 70322592 18933024
11:24:19 1198 8192000000 10070132032 10454736896 1100088912 11.838 100 97 188 0 1188879104 69593792 19196400
11:25:21 1153 8192000000 10057516816 10446364672 1101481328 11.852 100 97 184 3 1181077952 60775648 18820976
11:26:24 1173 8192000000 10070217904 10460127232 1105451744 11.889 100 98 191 0 1191159584 66545984 19161856
11:27:26 4437 8192000000 10055904208 10450247680 1094760112 11.788 100 79 154 0 1177245856 66806544 15679200
11:28:31 1860 8192000000 10058934656 10458042368 1097467536 11.814 100 84 162 2 1182860224 69300272 16092416
11:29:34 1467 8192000000 10048439792 10436001792 1079098784 11.639 100 81 148 4 1169359456 74947616 15313056
11:30:36 1105 8192000000 10047752896 10430185472 1072915408 11.580 100 91 174 3 1167458752 76264576 18278768
11:31:38 1027 8192000000 10034809024 10418831360 1076950080 11.618 100 92 173 0 1158433520 63769312 17714128
11:32:40 1184 8192000000 9979939824 10351534080 1035988896 11.226 100 95 175 3 1103581600 48032752 19559952
11:33:42 1092 8192000000 9961560720 10334343168 1032133808 11.189 100 83 157 2 1085558512 37910736 15513968
11:34:44 1413 8192000000 9988501376 10365558784 1026006016 11.130 100 72 131 3 1109350976 69884224 13460736
11:35:46 1784 8192000000 9982853792 10360991744 1044836432 11.311 100 82 150 1 1116996896 56020448 16140016
11:36:48 5896 8192000000 9987847072 10372362240 1045637088 11.319 100 61 121 1 1112396912 55157152 11602672
11:38:10 2372 8192000000 9988965904 10371301376 1054759456 11.406 100 92 169 4 1103795328 32761824 16274048
11:39:19 6850 8192000000 10009027472 10398982144 1067312416 11.526 100 72 134 3 1126715952 46745312 12658224
11:40:37 8644 8192000000 10034638800 10418327552 1081518352 11.662 100 74 132 2 1156724544 62464816 12741376
11:41:49 15037 8192000000 10074172480 10473443328 1099513248 11.833 100 55 102 1 1204316832 93258992 11544592
11:43:12 1334 8192000000 10115248000 10502832128 1111339968 11.945 100 94 178 1 1233130496 102757856 19032672
11:44:17 3846 8192000000 10127901696 10536017920 1119684208 12.024 100 58 113 0 1247713792 115795696 12233888
11:45:24 1730 8192000000 10167106464 10561290240 1122196656 12.048 100 88 167 1 1278939456 138543120 18199680
11:46:31 3100 8192000000 10167456688 10595856384 1127144528 12.094 100 82 162 0 1281954000 136672144 18137328
11:47:40 1053 8192000000 10176759408 10577702912 1145729888 12.269 100 92 165 2 1292229520 129791776 16707856
11:48:43 1068 8192000000 10221371472 10616426496 1155600768 12.362 100 97 194 0 1335310720 159328864 20381088
11:49:47 958 8192000000 10249741616 10652459008 1177582624 12.568 100 98 182 2 1358726640 159532672 21611344
11:50:51 1516 8192000000 10286717712 10692177920 1175400256 12.547 100 95 185 2 1399193008 204274416 19518336
11:51:56 908 8192000000 10318050608 10722091008 1193122560 12.712 100 97 191 1 1428568288 213915952 21529776
11:52:59 1460 8192000000 10345643360 10760781824 1184429040 12.631 100 96 182 2 1455315232 250313936 20572256
11:54:04 1087 8192000000 10347231152 10777907200 1177137328 12.563 99 96 188 1 1453015552 253004752 22873472
11:55:08 1079 8192000000 10362592608 10786746368 1176512240 12.558 100 95 180 2 1472156192 273932480 21711472
11:56:14 1203 8192000000 10364789696 10783600640 1169779776 12.495 100 94 177 1 1473606896 283006912 20820208
11:57:20 3725 8192000000 10390675776 10798759936 1170336912 12.500 100 70 134 0 1495338096 309292064 15709120
11:58:27 956 8192000000 10437537264 10838855680 1173526432 12.530 100 97 189 0 1542819584 346700256 22592896
11:59:30 3304 8192000000 10432237568 10848858112 1172241072 12.518 100 79 153 1 1541794240 349633424 19919744
12:00:38 1455 8192000000 10412424432 10860249088 1168666160 12.484 100 94 187 2 1516504576 325155536 22682880
12:01:43 2632 8192000000 10430051712 10874810368 1173170352 12.526 100 69 138 0 1541851776 351951152 16730272
12:02:48 3355 8192000000 10377772384 10812641280 1152914928 12.337 99 77 152 2 1485234352 314257552 18061872
12:03:53 9636 8192000000 10382943984 10802266112 1141763248 12.232 99 39 78 1 1473395344 321704688 9927408
12:05:05 6172 8192000000 10423799280 10846851072 1160938848 12.412 100 53 102 1 1527962864 354669264 12354752
12:06:17 10557 8192000000 10457716208 10882289664 1167637136 12.475 100 45 88 0 1553041808 373940192 11464480
12:07:36 27594 8192000000 10459929216 10921582592 1161119584 12.414 100 44 83 1 1557107600 384629376 11358640
12:09:15 6430 8192000000 10360641904 10892447744 1148759936 12.298 100 47 88 2 1537225424 377869216 10596272
12:10:25 24260 8192000000 10273938528 10773348352 1154802656 12.355 100 31 61 0 1416546496 253405488 8338352
12:11:51 17440 8192000000 10263430080 10724823040 1137225728 12.189 99 46 92 0 1380305200 231848048 11231424
12:13:10 15528 8192000000 10225204112 10694230016 1144338448 12.256 100 21 42 0 1335549696 185740352 5470896
12:15:20 80501 8192000000 10063994704 10508451840 1110954672 11.941 98 9 18 0 1136507536 23291504 2261360
12:17:53 92367 8192000000 10128701936 10579808256 1146790832 12.279 100 17 32 0 1182689520 33139616 2759072
12:20:30 58247 8192000000 10210302496 10676551680 1158003728 12.385 99 14 28 0 1283967456 122637456 3326272
12:23:12 93076 8192000000 10100466464 10569510912 1133534704 12.155 98 13 26 1 1172635104 36633568 2466832
12:25:46 62527 8192000000 10097475616 10528886784 1137723856 12.194 98 40 78 1 1164882832 18830816 8328160
12:28:00 63459 8192000000 10116146000 10574467072 1147505296 12.286 99 41 71 1 1209805296 53859392 8440608
12:30:04 38883 8192000000 10111338624 10553323520 1147342352 12.285 100 53 100 0 1211423040 52981264 11099424
12:31:46 62411 8192000000 10122388560 10559533056 1146511888 12.277 99 28 51 1 1209633760 57967600 5154272
12:33:49 11451 8192000000 10165014608 10601406464 1151769392 12.326 99 42 82 1 1251790144 91299888 8720864
12:35:02 6182 8192000000 10172883760 10601013248 1154382416 12.351 100 71 125 1 1274194176 103749200 16062560
12:36:15 56295 8192000000 10112431328 10634063872 1103386816 11.870 97 26 51 0 1275183440 165696816 6099808
12:38:16 38983 8192000000 10078132784 10542297088 1095096976 11.791 97 15 30 0 1171455648 73108048 3250624
12:40:28 41725 8192000000 10105916224 10603622400 1136750800 12.185 100 26 47 0 1205591984 63816400 5024784
12:42:10 90668 8192000000 10084744160 10547523584 1101123056 11.848 97 17 32 0 1161531008 56557936 3850016
12:44:52 42163 8192000000 10098899216 10543927296 1088649920 11.730 96 32 63 0 1168194352 73047568 6496864
12:46:41 63994 8192000000 10109519664 10570334208 1105170256 11.887 98 13 26 0 1221145456 113220832 2754368
12:48:51 82092 8192000000 10054589968 10484477952 1087238672 11.716 96 22 38 3 1120696352 29399072 4058608
12:51:16 32658 8192000000 10134721184 10585645056 1100355808 11.841 97 42 84 0 1200654976 91644912 8654256
12:53:33 156808 8192000000 10073548064 10505932800 1106565056 11.900 97 32 60 0 1158527008 45418032 6543920
12:57:10 3680 8192000000 10107771360 10537250816 1134942560 12.168 100 57 103 1 1209316048 64033312 10340176
12:58:14 71425 8192000000 10148853088 10644267008 1129270304 12.114 99 18 36 0 1255976576 122492672 4213600
13:00:26 50362 8192000000 10160253712 10608386048 1134837296 12.167 99 15 30 0 1246867328 108812432 3217600
13:02:45 72706 8192000000 10194381344 10717569024 1145120768 12.264 100 15 30 0 1325250688 176429904 3700016
13:05:42 76966 8192000000 10068227584 10533576704 1112463120 11.956 98 13 25 0 1143274160 28277168 2533872
13:08:01 54588 8192000000 10115043264 10549555200 1115896352 11.988 98 45 90 2 1185967232 60927008 9143872
13:10:00 30274 8192000000 10170797696 10615164928 1126201392 12.086 99 26 52 0 1247280160 115758208 5320560
13:11:33 37520 8192000000 10100514576 10566356992 1109758256 11.930 98 37 70 0 1207192800 90144256 7290288
13:13:12 13666 8192000000 10116725376 10557353984 1123544416 12.060 99 53 103 1 1178930352 44883616 10502320
13:14:30 88501 8192000000 10084100272 10534477824 1088014800 11.724 96 42 81 0 1164159760 67731360 8413600
13:16:59 126316 8192000000 10149602464 10628960256 1140437024 12.220 100 23 46 0 1248821936 103303264 5081648
13:20:26 70600 8192000000 10064866944 10549051392 1123156080 12.057 99 11 22 0 1162668448 37667024 1845344
13:22:37 47847 8192000000 10082253808 10544754688 1141168928 12.227 100 10 18 0 1183461552 40311552 1981072
13:24:26 50026 8192000000 10088866720 10529460224 1120692752 12.034 98 18 35 0 1183843664 59396448 3754464
13:26:17 32208 8192000000 10096950032 10543714304 1095523168 11.795 96 27 54 0 1160687152 59062672 6101312
13:27:49 50702 8192000000 10101107840 10538913792 1113954656 11.970 99 30 58 2 1197934624 77427952 6552016
13:29:41 39147 8192000000 10157620240 10600300544 1123132656 12.057 99 34 68 0 1254420528 123710688 7577184
13:31:38 56055 8192000000 10123905840 10559221760 1111167040 11.943 98 32 64 0 1198981360 81420944 6393376
13:33:44 9697 8192000000 10116019104 10540953600 1123750864 12.062 100 61 122 0 1195933920 59577984 12605072
13:35:02 49547 8192000000 10099246960 10564268032 1110762096 11.940 98 39 78 0 1214017536 95589968 7665472
13:36:52 82425 8192000000 8940606848 9163493376 105859088 1.275 10 3 6 0 142049072 35540128 649856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-01-2023 13:40:14 to 11-01-2023 13:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 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 11-01-2023 13:40:31 to 11-01-2023 13:40:33.

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          228402
MON$OLDEST_ACTIVE               228403
MON$OLDEST_SNAPSHOT             228403
MON$NEXT_TRANSACTION            228403
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                       2094016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
--- end of diff output ---

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

11.01.23 13:40:34. 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-01-11 13:40:37 No severe PSQL-related problems occured
11.01.23 13:40:38. End of report.