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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16424732 kB
MemAvailable 31396300 kB
Buffers 20 kB
Cached 15261172 kB
SwapCached 2544 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-24 04:37:18
DTS_END                         2022-05-24 04:37:18.6790
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/46976
TRN_ID                          206103
ATT_ID                          542
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: 8452.20 30331 1014264
customer order: creation 155.28 479 18634
customer order: refuse 13.22 462 1586
order to supplier: creation 42.96 1370 5155
order to supplier: removal 3.19 185856 383
invoice (draft): creation 42.54 3482 5105
invoice (draft): removal 47.03 1146 5643
invoice accept: apply 38.25 31390 4590
invoice accept: cancel 42.33 1315 5080
customer reserve: creation 3617.33 294 434080
customer reserve: removal 4172.68 109 500721
realization accept: apply 78.31 294 9397
realization accept: cancel 79.54 180 9545
payment from customer: creation 55.45 193 6654
payment from customer: removal 5.01 159 601
payment to supplier: creation 44.07 214 5288
payment to supplier: removal 3.12 166 374
service: total inventory turnovers 3.73 4969 448
service: total monetary turnovers 7.27 379 872
service: aggregate perf. data 0.86 17785 103
service: refresh index statistics 0.04 356386 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-05-2022 04:39:02 to 24-05-2022 04:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9127
TEST_TIME 2 6651
TEST_TIME 3 6004
TEST_TIME 4 5761
TEST_TIME 5 5770
TEST_TIME 6 5590
TEST_TIME 7 5318
TEST_TIME 8 5645
TEST_TIME 9 5900
TEST_TIME 10 5904
TEST_TIME 11 6126
TEST_TIME 12 6202
TEST_TIME 13 6240
TEST_TIME 14 6345
TEST_TIME 15 6297
TEST_TIME 16 6318
TEST_TIME 17 6466
TEST_TIME 18 6539
TEST_TIME 19 6857
TEST_TIME 20 6775
TEST_TIME 21 7107
TEST_TIME 22 7275
TEST_TIME 23 7213
TEST_TIME 24 7430
TEST_TIME 25 7437
TEST_TIME 26 7486
TEST_TIME 27 7477
TEST_TIME 28 7840
TEST_TIME 29 7942
TEST_TIME 30 8052
TEST_TIME 31 8252
TEST_TIME 32 9026
TEST_TIME 33 9278
TEST_TIME 34 9389
TEST_TIME 35 9602
TEST_TIME 36 10044
TEST_TIME 37 10252
TEST_TIME 38 10204
TEST_TIME 39 10034
TEST_TIME 40 9883
TEST_TIME 41 9797
TEST_TIME 42 9676
TEST_TIME 43 9582
TEST_TIME 44 9508
TEST_TIME 45 9447
TEST_TIME 46 9404
TEST_TIME 47 9376
TEST_TIME 48 9261
TEST_TIME 49 9199
TEST_TIME 50 9221
TEST_TIME 51 9073
TEST_TIME 52 9065
TEST_TIME 53 8999
TEST_TIME 54 9031
TEST_TIME 55 8976
TEST_TIME 56 8889
TEST_TIME 57 8930
TEST_TIME 58 9041
TEST_TIME 59 8994
TEST_TIME 60 8953
TEST_TIME 61 8902
TEST_TIME 62 8885
TEST_TIME 63 8834
TEST_TIME 64 8822
TEST_TIME 65 8851
TEST_TIME 66 8873
TEST_TIME 67 8853
TEST_TIME 68 8849
TEST_TIME 69 8826
TEST_TIME 70 8709
TEST_TIME 71 8622
TEST_TIME 72 8599
TEST_TIME 73 8561
TEST_TIME 74 8497
TEST_TIME 75 8443
TEST_TIME 76 8472
TEST_TIME 77 8507
TEST_TIME 78 8538
TEST_TIME 79 8559
TEST_TIME 80 8516
TEST_TIME 81 8517
TEST_TIME 82 8514
TEST_TIME 83 8626
TEST_TIME 84 8699
TEST_TIME 85 8661
TEST_TIME 86 8662
TEST_TIME 87 8654
TEST_TIME 88 8670
TEST_TIME 89 8779
TEST_TIME 90 8716
TEST_TIME 91 8651
TEST_TIME 92 8602
TEST_TIME 93 8569
TEST_TIME 94 8532
TEST_TIME 95 8495
TEST_TIME 96 8449
TEST_TIME 97 8436
TEST_TIME 98 8401
TEST_TIME 99 8451
TEST_TIME 100 8546
TEST_TIME 101 8534
TEST_TIME 102 8519
TEST_TIME 103 8491
TEST_TIME 104 8510
TEST_TIME 105 8530
TEST_TIME 106 8614
TEST_TIME 107 8588
TEST_TIME 108 8580
TEST_TIME 109 8626
TEST_TIME 110 8692
TEST_TIME 111 8669
TEST_TIME 112 8642
TEST_TIME 113 8714
TEST_TIME 114 8666
TEST_TIME 115 8605
TEST_TIME 116 8561
TEST_TIME 117 8569
TEST_TIME 118 8546
TEST_TIME 119 8546
TEST_TIME 120 8485

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-05-2022 04:39:06 to 24-05-2022 04:39:10.

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 1413315 1413315 0 0.00 0 24676 15 0 0
doc_list_biud 1413304 1413304 0 0.00 0 38451 45 0 0
sp_add_invoice_to_stock 4687 4590 97 2.06 56 256965 31391 0 97
sp_cancel_adding_invoice 5080 5080 0 0.00 8 80133 1316 0 0
sp_cancel_client_order 1586 1586 0 0.00 6 9791 462 0 0
sp_cancel_customer_reserve 500721 500721 0 0.00 1 38484 110 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 4 7095 160 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 4 8470 166 0 0
sp_cancel_supplier_invoice 5649 5643 6 0.10 4 24836 1146 0 6
sp_cancel_supplier_order 383 383 0 0.00 12 1555747 185856 0 0
sp_cancel_write_off 9545 9545 0 0.00 2 10546 181 0 0
sp_client_order 18634 18634 0 0.00 6 29676 479 0 0
sp_customer_reserve 434080 434080 0 0.00 1 50609 294 0 0
sp_fill_shopping_cart 28881 28881 0 0.00 2 11225 86 0 0
sp_fill_shopping_cart_clo_res 434044 434044 0 0.00 0 21189 19 0 0
sp_get_clo_for_invoice 4540 4540 0 0.00 0 33532 307 0 0
sp_kill_cost_storno 15600 15600 0 0.00 0 10539 83 0 0
sp_kill_qstorno_ret_qs2qd 1017013 1017013 0 0.00 0 38414 48 0 0
sp_kill_qty_storno 536892 536892 0 0.00 0 38449 111 0 0
sp_lock_selected_doc 19190 19190 0 0.00 0 5605 7 0 0
sp_make_cost_storno 25877 25877 0 0.00 0 24670 83 0 0
sp_multiply_rows_for_pdistr 25876 25876 0 0.00 0 9877 43 0 0
sp_multiply_rows_for_qdistr 18633 18633 0 0.00 0 29666 176 0 0
sp_pay_from_customer 6654 6654 0 0.00 5 17921 193 0 0
sp_pay_to_supplier 5288 5288 0 0.00 7 20658 215 0 0
sp_payment_common 11941 11941 0 0.00 2 9202 46 0 0
sp_qd_handle_on_cancel_clo 1586 1586 0 0.00 0 8187 380 0 0
sp_qd_handle_on_invoice_upd_sts 9617 9617 0 0.00 0 26858 590 0 0
sp_qd_handle_on_reserve_upd_sts 9397 9397 0 0.00 0 17452 64 0 0
sp_reserve_write_off 9397 9397 0 0.00 8 24710 295 0 0
sp_supplier_invoice 5105 5105 0 0.00 12 89665 3482 0 0
sp_supplier_order 5155 5155 0 0.00 8 38921 1370 0 0
srv_aggregate_perf_data 103 103 0 0.00 2388 152739 17786 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 732 155529 35466 0 0
srv_find_qd_qs_mism 968657 968657 0 0.00 0 14702 12 0 0
srv_make_invnt_saldo 448 448 0 0.00 32 159240 4970 0 0
srv_make_money_saldo 872 872 0 0.00 1 6629 379 0 0
srv_recalc_idx_stat 5 5 0 0.00 235383 476047 356386 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 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 4595 4595 0 0.00 1 18632 1052 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 7753 422 0 0
t$perf-norm:sp_cancel_client_order 1586 1586 0 0.00 0 9354 36 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 1333 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 592 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 333 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 1991 43 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 106708 8373 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 3788 21 0 0
t$perf-norm:sp_client_order 18634 18634 0 0.00 0 12829 41 0 0
t$perf-norm:sp_customer_reserve 913 913 0 0.00 0 1307 16 0 0
t$perf-norm:sp_pay_from_customer 6654 6654 0 0.00 0 6009 21 0 0
t$perf-norm:sp_pay_to_supplier 5288 5288 0 0.00 0 3691 23 0 0
t$perf-norm:sp_reserve_write_off 9397 9397 0 0.00 0 5871 26 0 0
t$perf-norm:sp_supplier_invoice 5105 5105 0 0.00 0 6785 39 0 0
t$perf-norm:sp_supplier_order 5155 5155 0 0.00 0 4749 37 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1714 50 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 5562 87 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 2486 26 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 1114 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 178 76 0 0
v_add_invoice_to_stock 9256 9062 194 2.09 0 8349 27 0 194
v_all_customers 34412 34412 0 0.00 0 120 4 0 0
v_all_suppliers 28244 28244 0 0.00 0 115 4 0 0
v_all_wares 37254 37254 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2101 16 0 0
v_cancel_client_order 3172 3172 0 0.00 0 6416 16 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 7028 26 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 0 8399 20 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 8403 23 0 0
v_cancel_supplier_invoice 750 738 12 1.60 0 5428 34 0 12
v_cancel_supplier_order 766 766 0 0.00 0 920 12 0 0
v_cancel_write_off 1886 1886 0 0.00 0 2297 24 0 0
v_max_id_clo_ord 5149 5149 0 0.00 0 854 5 0 0
v_max_id_clo_res 913 913 0 0.00 0 1245 10 0 0
v_max_id_ord_sup 5098 5098 0 0.00 0 1739 5 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 489 8 0 0
v_max_non_paid_realizn 3448 3448 0 0.00 0 5676 8 0 0
v_min_id_clo_ord 5149 5149 0 0.00 0 2534 9 0 0
v_min_id_clo_res 913 913 0 0.00 0 10859 34 0 0
v_min_id_ord_sup 5098 5098 0 0.00 0 5862 8 0 0
v_min_non_paid_invoice 5287 5287 0 0.00 0 4289 16 0 0
v_min_non_paid_realizn 6654 6654 0 0.00 0 5990 19 0 0
v_our_firm 9254 9254 0 0.00 0 125 5 0 0
v_reserve_write_off 18792 18792 0 0.00 0 13912 12 0 0
x_lock_depdocs_on_canc_invoice 10354 10354 0 0.00 0 17423 141 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 5179 399 0 0
x_make_qstorno_1000_1200 5155 5155 0 0.00 3 38910 1195 0 0
x_make_qstorno_1200_2000 5105 5105 0 0.00 5 81398 3235 0 0
x_make_qstorno_2100_3300 434078 434078 0 0.00 0 46685 262 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-05-2022 04:39:11 to 24-05-2022 04:39:15.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:22 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 25775 8192000000 9721820832 10018717696 791113616 8.806 100 89 178 0 821468960 7695168 22660176
02:09:56 17723 8192000000 9775776928 10084024320 844338896 9.343 100 86 172 0 876509392 11869856 20300640
02:11:19 20271 8192000000 9801598928 10123743232 870702816 9.607 100 83 166 0 912142160 22306704 19132640
02:12:45 14311 8192000000 9877954432 10210631680 916041536 10.057 100 81 162 0 975533056 41409568 18081952
02:14:02 9411 8192000000 9923865456 10266664960 961329872 10.502 100 82 164 0 1035253760 56476960 17446928
02:15:19 6837 8192000000 9948622720 10304139264 994963552 10.830 100 83 166 1 1066373632 54149648 17260432
02:16:29 7021 8192000000 9990372560 10352783360 1020321104 11.075 100 81 153 1 1113087888 78030208 14736576
02:17:38 1532 8192000000 10016585216 10389725184 1044949344 11.312 100 94 182 0 1139304512 75823456 18531712
02:18:43 2023 8192000000 10033714128 10411810816 1060716704 11.463 100 88 174 1 1156924016 78926320 17280992
02:19:53 1820 8192000000 10055619920 10429943808 1070112704 11.553 100 92 178 0 1176139120 88351280 17675136
02:20:56 1456 8192000000 10062220944 10439409664 1077552864 11.624 100 97 190 1 1178582592 81510176 19519552
02:22:02 1306 8192000000 10065316960 10448711680 1085022880 11.695 100 94 184 0 1191836432 87797440 19016112
02:23:05 1277 8192000000 10065074512 10450313216 1084250368 11.688 100 90 168 1 1188104416 86766304 17087744
02:24:09 2310 8192000000 10056690192 10439688192 1088708352 11.730 100 91 168 0 1176321728 71134816 16478560
02:25:14 1228 8192000000 10050955088 10435997696 1093584544 11.777 100 97 183 0 1172634928 61360320 17690064
02:26:17 1367 8192000000 10093674624 10482171904 1096274016 11.802 100 97 191 0 1216369920 102140512 17955392
02:27:20 1166 8192000000 10063515840 10455240704 1098473472 11.823 100 94 182 0 1189165648 71481616 19210560
02:28:23 1299 8192000000 10090667504 10480082944 1106666512 11.901 100 94 181 1 1211605632 86074032 18865088
02:29:26 1392 8192000000 10066213248 10452889600 1086809792 11.712 100 95 185 4 1188909920 83230224 18869904
02:30:28 1118 8192000000 10052732576 10438520832 1096589952 11.805 100 96 185 0 1174399536 58608736 19200848
02:31:31 1150 8192000000 10036851888 10422214656 1083941312 11.685 99 94 172 3 1155075424 54885248 16248864
02:32:34 2298 8192000000 10029901648 10416250880 1072397872 11.575 99 79 152 1 1147677312 59305600 15973840
02:33:37 1385 8192000000 10043716368 10428403712 1062684400 11.482 100 96 183 0 1165735792 85576736 17474656
02:34:40 1370 8192000000 10033575552 10415857664 1070730608 11.559 100 96 178 1 1155756128 66167840 18857680
02:35:43 1135 8192000000 9989717936 10368000000 1024890848 11.119 99 80 155 0 1109196256 69454816 14850592
02:36:45 1119 8192000000 10000780608 10371231744 1039989920 11.265 100 97 181 0 1119395552 60133408 19272224
02:37:53 7163 8192000000 10001269600 10378805248 1049519808 11.356 100 73 139 2 1121329024 57179216 14630000
02:39:09 27136 8192000000 10025851984 10414391296 1059970336 11.456 100 63 122 1 1152298416 80364976 11963104
02:40:41 766 8192000000 10040545920 10426060800 1073745552 11.588 100 79 150 0 1162038848 73005296 15288000
02:41:47 8174 8192000000 10037470704 10423988224 1073330272 11.584 100 59 118 3 1153613056 67962928 12319856
02:42:58 6575 8192000000 10084917296 10485686272 1080338128 11.651 99 74 137 0 1197367168 101976160 15052880
02:44:10 15736 8192000000 10108042992 10502840320 1091746432 11.759 100 61 118 1 1223191600 118732560 12712608
02:45:34 1025 8192000000 10159537984 10567983104 1112039424 11.952 100 90 167 2 1274760496 144555712 18165360
02:46:41 4577 8192000000 10184688528 10573660160 1127570800 12.098 100 87 170 0 1304240848 159290528 17379520
02:47:51 1894 8192000000 10212777344 10606452736 1137669264 12.194 100 74 141 1 1319820064 167046224 15104576
02:49:00 3211 8192000000 10252933872 10650185728 1154205568 12.349 100 77 151 1 1365808464 195695872 15907024
02:50:08 1508 8192000000 10273749696 10682400768 1171754048 12.513 100 80 151 0 1385839936 196964784 17121104
02:51:15 1028 8192000000 10313933696 10711523328 1177734656 12.569 100 97 188 0 1428578800 229860320 20983824
02:52:20 2693 8192000000 10347922656 10750861312 1177039504 12.563 100 91 176 2 1459180112 262062640 20077968
02:53:27 1575 8192000000 10387677920 10789023744 1187102320 12.656 100 93 180 2 1497976368 289759792 21114256
02:54:35 1325 8192000000 10437061504 10843709440 1194610512 12.726 100 89 176 1 1545228688 330564736 20053440
02:55:41 1254 8192000000 10434584496 10885001216 1198190816 12.760 100 92 172 0 1553348640 332771552 22386272
02:56:49 747 8192000000 10455228464 10863386624 1201709536 12.792 100 94 182 1 1558486576 334962720 21814320
02:57:53 875 8192000000 10491065616 10929934336 1202275232 12.797 100 95 186 0 1589806848 366093568 21438048
02:58:59 724 8192000000 10494155568 10924118016 1198236944 12.760 100 95 186 1 1599805776 379567376 22001456
03:00:05 1173 8192000000 10530775168 10940071936 1201880144 12.794 100 90 180 0 1637301184 413816992 21604048
03:01:11 1091 8192000000 10573537600 10999685120 1207241840 12.844 100 88 174 0 1677545760 449115152 21188768
03:02:18 1063 8192000000 10572505888 11005444096 1210146608 12.870 100 91 180 1 1672933904 439696672 23090624
03:03:27 4058 8192000000 10594625280 11017207808 1201822464 12.793 100 73 145 0 1696094352 476173360 18098528
03:04:38 4917 8192000000 10607347024 11078094848 1193882560 12.719 100 71 137 2 1709846960 498208864 17755536
03:05:47 8770 8192000000 10547815664 11015139328 1164306928 12.444 100 53 103 1 1674820176 496659840 13853408
03:07:04 4838 8192000000 10551564960 11010842624 1152359520 12.332 99 67 132 1 1654942080 485467488 17115072
03:08:18 3479 8192000000 10532749584 11014635520 1157694224 12.382 100 69 138 0 1638527824 464005552 16828048
03:09:24 10359 8192000000 10416780096 10975891456 1171159024 12.508 100 65 125 0 1536848096 350770512 14918560
03:10:36 11955 8192000000 10349742784 10813394944 1159716064 12.401 100 34 68 0 1442464128 273819600 8928464
03:12:28 31326 8192000000 10238493312 10756112384 1128951200 12.111 99 20 40 0 1394472528 260233072 5288256
03:14:02 43507 8192000000 10081266448 10513350656 1104168112 11.877 98 27 54 1 1164752944 54448272 6136560
03:15:49 15501 8192000000 10099680800 10529439744 1132556528 12.145 100 50 97 1 1198298448 54516448 11225472
03:18:22 46029 8192000000 10100722960 10558525440 1138183392 12.198 100 50 93 1 1195194368 46105696 10905280
03:20:11 86005 8192000000 10103568656 10580008960 1137879104 12.196 99 28 55 0 1198373024 54616576 5877344
03:22:44 49031 8192000000 10102281152 10543951872 1135283680 12.171 99 24 47 1 1207683952 66835408 5564864
03:25:00 41804 8192000000 10137102352 10595155968 1153036736 12.338 100 15 30 0 1237617056 80882672 3697648
03:26:42 59375 8192000000 10119925648 10599350272 1126440928 12.088 98 15 30 0 1232691216 102641584 3608704
03:28:54 43349 8192000000 10119224144 10605481984 1142657856 12.241 100 18 36 0 1228989328 82401440 3930032
03:30:41 46101 8192000000 10075140704 10577018880 1123444960 12.060 98 9 18 1 1163582480 37942736 2194784
03:32:28 30734 8192000000 10113434400 10568679424 1155912352 12.365 100 34 64 1 1216202288 53703984 6585952
03:33:59 82597 8192000000 10094017888 10590871552 1141722464 12.232 99 18 36 1 1224340736 78191440 4426832
03:36:55 34145 8192000000 10078523856 10520842240 1111225232 11.944 97 42 83 0 1143717136 23522224 8969680
03:38:45 88735 8192000000 10107098512 10565693440 1148260880 12.293 100 30 60 1 1215507584 61100672 6146032
03:41:21 155529 8192000000 10095977968 10567356416 1121768048 12.044 97 21 40 2 1202019152 76267888 3983216
03:44:57 21298 8192000000 10093686800 10529771520 1121090016 12.037 98 37 72 1 1172417952 43402608 7925328
03:46:28 85250 8192000000 10105492592 10540863488 1124301168 12.068 98 51 97 2 1174567152 40728160 9537824
03:49:11 46039 8192000000 10139079968 10612105216 1129715728 12.119 99 31 62 0 1233284016 97544432 6023856
03:51:18 57419 8192000000 10084320688 10550755328 1116028864 11.989 98 16 31 0 1186530288 67246720 3254704
03:53:40 66783 8192000000 10076798912 10557378560 1110619776 11.938 97 17 34 1 1169057744 54887280 3550688
03:55:57 56368 8192000000 10053217552 10503270400 1078446320 11.633 94 12 24 0 1116304208 35412048 2445840
03:57:58 43303 8192000000 10128865888 10576052224 1110548048 11.938 98 40 80 2 1211932896 93279120 8105728
03:59:43 87460 8192000000 10113105728 10567540736 1110469888 11.937 98 16 32 1 1214940400 100476688 3993824
04:03:04 23130 8192000000 10087418640 10524635136 1130911808 12.130 100 43 86 1 1178155296 38812400 8431088
04:04:27 1988 8192000000 10091573456 10502299648 1110869664 11.941 99 70 140 0 1181566864 56901024 13796176
04:05:32 39986 8192000000 10135785440 10586714112 1096229408 11.802 99 35 70 0 1222378032 118608736 7539888
04:07:13 104999 8192000000 10029140656 10473816064 1107130352 11.905 100 25 49 1 1137766112 24798480 5837280
04:10:05 32684 8192000000 10088515968 10525495296 1115390016 11.983 99 34 67 0 1198563264 76137680 7035568
04:11:39 47463 8192000000 10098034976 10567725056 1113561744 11.966 99 18 36 1 1200307312 83015632 3729936
04:14:04 104952 8192000000 10169488800 10658512896 1078337520 11.632 95 20 40 2 1223591152 140387584 4866048
04:17:23 111122 8192000000 10053019648 10508394496 1111947344 11.951 99 9 18 0 1156746528 43087328 1711856
04:20:18 47508 8192000000 10122070736 10581667840 1138460592 12.201 100 41 79 1 1217403152 71059792 7882768
04:22:12 57371 8192000000 10108941520 10592800768 1098068944 11.819 97 34 68 0 1205564592 100296112 7199536
04:24:10 60845 8192000000 10102344832 10544242688 1138614240 12.202 100 20 38 3 1188146896 45859776 3672880
04:26:18 57463 8192000000 10125230848 10677743616 1141098928 12.226 100 19 38 0 1237305184 92526624 3679632
04:28:52 55404 8192000000 10160089408 10612350976 1146476096 12.276 100 35 70 2 1231487552 77727264 7284192
04:31:08 130856 8192000000 10069907056 10516041728 1126177568 12.085 98 39 76 2 1171089600 37033184 7878848
04:35:06 62399 8192000000 10087513216 10565873664 1129679648 12.118 99 4 8 0 1168630736 38177424 773664
04:37:13 15920 8192000000 9138286288 9432051712 292828256 3.451 26 5 10 0 306030000 12306064 895680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-05-2022 04:39:16 to 24-05-2022 04:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 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 24-05-2022 04:39:33 to 24-05-2022 04:39:35.

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          206495
MON$OLDEST_ACTIVE               206496
MON$OLDEST_SNAPSHOT             206496
MON$NEXT_TRANSACTION            206496
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                       2037984
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             744
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

24.05.22 04:39:36. 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-05-24 04:39:39 No severe PSQL-related problems occured
24.05.22 04:39:40. End of report.