Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2021 10: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 16655708 kB
MemAvailable 31503948 kB
Buffers 0 kB
Cached 15071428 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-12 10:37:08
DTS_END                         2021-12-12 10:37:08.7140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56308
TRN_ID                          235676
ATT_ID                          636
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: 9164.39 17915 1099727
customer order: creation 174.15 394 20898
customer order: refuse 15.11 333 1813
order to supplier: creation 45.95 1494 5514
order to supplier: removal 3.55 98255 426
invoice (draft): creation 45.45 3682 5454
invoice (draft): removal 51.20 942 6144
invoice accept: apply 42.32 18967 5078
invoice accept: cancel 45.92 772 5510
customer reserve: creation 3941.39 186 472967
customer reserve: removal 4510.70 60 541284
realization accept: apply 84.58 190 10149
realization accept: cancel 73.96 120 8875
payment from customer: creation 59.63 121 7156
payment from customer: removal 5.88 94 705
payment to supplier: creation 50.07 125 6008
payment to supplier: removal 3.32 69 398
service: total inventory turnovers 3.98 3247 477
service: total monetary turnovers 6.30 468 756
service: aggregate perf. data 0.93 14065 111
service: refresh index statistics 0.03 214714 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-12-2021 10:39:53 to 12-12-2021 10:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9076
TEST_TIME 2 6635
TEST_TIME 3 5539
TEST_TIME 4 6254
TEST_TIME 5 6095
TEST_TIME 6 6271
TEST_TIME 7 6138
TEST_TIME 8 6038
TEST_TIME 9 6040
TEST_TIME 10 5944
TEST_TIME 11 6100
TEST_TIME 12 6355
TEST_TIME 13 6371
TEST_TIME 14 6846
TEST_TIME 15 6990
TEST_TIME 16 7209
TEST_TIME 17 7265
TEST_TIME 18 8007
TEST_TIME 19 8540
TEST_TIME 20 8673
TEST_TIME 21 9090
TEST_TIME 22 9542
TEST_TIME 23 10187
TEST_TIME 24 10715
TEST_TIME 25 10812
TEST_TIME 26 10578
TEST_TIME 27 10732
TEST_TIME 28 10676
TEST_TIME 29 10774
TEST_TIME 30 10619
TEST_TIME 31 10425
TEST_TIME 32 10240
TEST_TIME 33 10228
TEST_TIME 34 10055
TEST_TIME 35 9976
TEST_TIME 36 9952
TEST_TIME 37 9921
TEST_TIME 38 9940
TEST_TIME 39 9901
TEST_TIME 40 9757
TEST_TIME 41 9722
TEST_TIME 42 9743
TEST_TIME 43 9756
TEST_TIME 44 9669
TEST_TIME 45 9663
TEST_TIME 46 9730
TEST_TIME 47 9762
TEST_TIME 48 9773
TEST_TIME 49 9795
TEST_TIME 50 9857
TEST_TIME 51 9806
TEST_TIME 52 9934
TEST_TIME 53 9987
TEST_TIME 54 10054
TEST_TIME 55 9965
TEST_TIME 56 9851
TEST_TIME 57 9721
TEST_TIME 58 9682
TEST_TIME 59 9606
TEST_TIME 60 9499
TEST_TIME 61 9474
TEST_TIME 62 9460
TEST_TIME 63 9505
TEST_TIME 64 9517
TEST_TIME 65 9589
TEST_TIME 66 9526
TEST_TIME 67 9665
TEST_TIME 68 9617
TEST_TIME 69 9706
TEST_TIME 70 9648
TEST_TIME 71 9707
TEST_TIME 72 9718
TEST_TIME 73 9743
TEST_TIME 74 9674
TEST_TIME 75 9751
TEST_TIME 76 9758
TEST_TIME 77 9664
TEST_TIME 78 9665
TEST_TIME 79 9588
TEST_TIME 80 9511
TEST_TIME 81 9495
TEST_TIME 82 9459
TEST_TIME 83 9417
TEST_TIME 84 9395
TEST_TIME 85 9398
TEST_TIME 86 9408
TEST_TIME 87 9397
TEST_TIME 88 9395
TEST_TIME 89 9408
TEST_TIME 90 9406
TEST_TIME 91 9475
TEST_TIME 92 9507
TEST_TIME 93 9530
TEST_TIME 94 9558
TEST_TIME 95 9552
TEST_TIME 96 9552
TEST_TIME 97 9602
TEST_TIME 98 9513
TEST_TIME 99 9437
TEST_TIME 100 9451
TEST_TIME 101 9395
TEST_TIME 102 9362
TEST_TIME 103 9319
TEST_TIME 104 9286
TEST_TIME 105 9274
TEST_TIME 106 9263
TEST_TIME 107 9230
TEST_TIME 108 9238
TEST_TIME 109 9211
TEST_TIME 110 9236
TEST_TIME 111 9288
TEST_TIME 112 9289
TEST_TIME 113 9287
TEST_TIME 114 9309
TEST_TIME 115 9311
TEST_TIME 116 9349
TEST_TIME 117 9320
TEST_TIME 118 9268
TEST_TIME 119 9235
TEST_TIME 120 9166

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-12-2021 10:39:57 to 12-12-2021 10:40:01.

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 1535106 1535106 0 0.00 0 22558 7 0 0
doc_list_biud 1535091 1535091 0 0.00 0 69019 27 0 0
sp_add_invoice_to_stock 5221 5078 143 2.73 31 204221 18968 0 143
sp_cancel_adding_invoice 5510 5510 0 0.00 5 79687 773 0 0
sp_cancel_client_order 1813 1813 0 0.00 5 44780 334 0 0
sp_cancel_customer_reserve 541284 541284 0 0.00 1 56055 60 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 9081 94 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 3386 69 0 0
sp_cancel_supplier_invoice 6153 6144 9 0.14 2 69024 942 0 9
sp_cancel_supplier_order 426 426 0 0.00 8 1184611 98256 0 0
sp_cancel_write_off 8875 8875 0 0.00 1 11279 120 0 0
sp_client_order 20898 20898 0 0.00 4 72642 395 0 0
sp_customer_reserve 472967 472967 0 0.00 0 72892 187 0 0
sp_fill_shopping_cart 31835 31835 0 0.00 1 15240 48 0 0
sp_fill_shopping_cart_clo_res 472963 472963 0 0.00 0 15020 15 0 0
sp_get_clo_for_invoice 5074 5074 0 0.00 0 19595 333 0 0
sp_kill_cost_storno 15488 15488 0 0.00 0 5953 54 0 0
sp_kill_qstorno_ret_qs2qd 1098013 1098013 0 0.00 0 69017 33 0 0
sp_kill_qty_storno 579274 579274 0 0.00 0 69018 72 0 0
sp_lock_selected_doc 21087 21087 0 0.00 0 10059 7 0 0
sp_make_cost_storno 28387 28387 0 0.00 0 13851 55 0 0
sp_multiply_rows_for_pdistr 28386 28386 0 0.00 0 10011 32 0 0
sp_multiply_rows_for_qdistr 20889 20889 0 0.00 0 15448 196 0 0
sp_pay_from_customer 7156 7156 0 0.00 4 16521 122 0 0
sp_pay_to_supplier 6008 6008 0 0.00 5 14821 126 0 0
sp_payment_common 13164 13164 0 0.00 1 14345 27 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 44773 302 0 0
sp_qd_handle_on_invoice_upd_sts 10583 10583 0 0.00 0 31319 325 0 0
sp_qd_handle_on_reserve_upd_sts 10149 10149 0 0.00 0 13694 56 0 0
sp_reserve_write_off 10149 10149 0 0.00 7 25018 190 0 0
sp_supplier_invoice 5454 5454 0 0.00 7 105357 3683 0 0
sp_supplier_order 5514 5514 0 0.00 6 75847 1495 0 0
srv_aggregate_perf_data 111 111 0 0.00 2783 149634 14066 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1897 131978 48910 0 0
srv_find_qd_qs_mism 1050072 1050072 0 0.00 0 22556 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 45 92960 3247 0 0
srv_make_money_saldo 756 756 0 0.00 1 18499 468 0 0
srv_recalc_idx_stat 4 4 0 0.00 80456 317510 214715 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 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 5078 5078 0 0.00 0 10260 176 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 5599 78 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 5081 15 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 449 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 2604 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 232 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 3047 27 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 21600 1368 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 7294 14 0 0
t$perf-norm:sp_client_order 20898 20898 0 0.00 0 8993 18 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 4710 12 0 0
t$perf-norm:sp_pay_from_customer 7156 7156 0 0.00 0 6429 9 0 0
t$perf-norm:sp_pay_to_supplier 6008 6008 0 0.00 0 5071 9 0 0
t$perf-norm:sp_reserve_write_off 10149 10149 0 0.00 0 7070 13 0 0
t$perf-norm:sp_supplier_invoice 5454 5454 0 0.00 0 9838 20 0 0
t$perf-norm:sp_supplier_order 5514 5514 0 0.00 0 7309 19 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 344 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 84 2 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 1442 13 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 6842 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10426 10140 286 2.74 0 6103 16 0 286
v_all_customers 37772 37772 0 0.00 0 98 1 0 0
v_all_suppliers 30816 30816 0 0.00 0 32 1 0 0
v_all_wares 41774 41774 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1173 8 0 0
v_cancel_client_order 3626 3626 0 0.00 0 3066 3 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 839 5 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 626 4 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1699 6 0 0
v_cancel_supplier_invoice 814 796 18 2.21 0 911 13 0 18
v_cancel_supplier_order 852 852 0 0.00 0 2894 10 0 0
v_cancel_write_off 2026 2026 0 0.00 0 5927 24 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 1132 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 2684 8 0 0
v_max_id_ord_sup 5442 5442 0 0.00 0 8297 9 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 228 2 0 0
v_max_non_paid_realizn 3947 3947 0 0.00 0 6025 6 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 9917 7 0 0
v_min_id_clo_res 976 976 0 0.00 0 7195 11 0 0
v_min_id_ord_sup 5442 5442 0 0.00 0 4404 9 0 0
v_min_non_paid_invoice 6008 6008 0 0.00 0 6085 15 0 0
v_min_non_paid_realizn 7156 7156 0 0.00 0 10025 19 0 0
v_our_firm 10420 10420 0 0.00 0 132 1 0 0
v_reserve_write_off 20290 20290 0 0.00 0 5907 5 0 0
x_lock_depdocs_on_canc_invoice 11256 11256 0 0.00 0 21991 102 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7714 297 0 0
x_make_qstorno_1000_1200 5514 5514 0 0.00 1 75837 1424 0 0
x_make_qstorno_1200_2000 5454 5454 0 0.00 2 105348 3478 0 0
x_make_qstorno_2100_3300 472967 472967 0 0.00 0 72890 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2021 10:40:01 to 12-12-2021 10:40:05.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:12 3 8192000000 8799878448 8811700224 5550000 0.067 2 1 2 0 5680624 68400 62224
08:08:13 29003 8192000000 9713209264 10007359488 790792912 8.803 100 81 162 0 818360560 7966176 19601472
08:09:56 20764 8192000000 9762090416 10068566016 835425600 9.254 100 87 174 0 875366800 19418400 20522800
08:11:27 24159 8192000000 9839141072 10160791552 893455584 9.833 100 88 174 2 941446400 28754288 19236528
08:12:55 12522 8192000000 9883380976 10221555712 937910544 10.272 100 70 138 2 987841712 34963616 14967552
08:14:11 6378 8192000000 9934227680 10281054208 977378880 10.659 100 85 168 0 1047704352 52881248 17444224
08:15:20 3132 8192000000 9969624624 10324279296 1003935376 10.917 100 81 160 0 1091654064 70845280 16873408
08:16:25 2553 8192000000 10007805584 10367447040 1026445680 11.134 100 91 180 1 1118351184 73927424 17978080
08:17:29 2255 8192000000 10017947008 10385924096 1052120928 11.381 100 85 172 0 1126444992 56859296 17464768
08:18:33 7564 8192000000 10027680496 10405060608 1064201552 11.497 100 71 142 0 1146220080 67636768 14381760
08:19:42 5234 8192000000 9996288784 10388832256 1075880384 11.608 100 35 70 1 1112663888 29149248 7634256
08:20:51 20168 8192000000 9996115376 10383450112 1079437456 11.642 100 70 140 2 1104567936 9411952 15718528
08:22:11 3829 8192000000 10011697360 10408067072 1088547408 11.729 100 48 94 0 1124698800 25365104 10786288
08:23:21 13208 8192000000 10009018640 10414632960 1085632784 11.701 99 26 52 1 1118133600 26300864 6199952
08:24:34 48333 8192000000 10025110800 10417152000 1097546912 11.814 100 58 117 1 1130274112 20116208 12610992
08:26:23 6304 8192000000 10004009104 10407862272 1096643136 11.806 100 38 76 1 1121125680 15425792 9056752
08:27:30 8439 8192000000 10016557152 10420838400 1080805008 11.655 98 37 74 0 1101585584 12329472 8451104
08:28:40 8597 8192000000 9963345840 10358398976 1034073104 11.208 97 29 59 0 1063625264 22853264 6698896
08:29:49 55208 8192000000 9933201504 10332372992 962129712 10.510 92 26 52 0 995731632 27613872 5988048
08:31:45 6772 8192000000 9928971712 10304548864 1004510848 10.922 98 38 75 1 1034454992 21892464 8051680
08:32:53 8721 8192000000 9942790736 10331103232 1024789328 11.118 99 41 80 0 1061199808 26974800 9435680
08:34:04 12514 8192000000 9950098080 10346999808 1039715616 11.262 100 30 59 0 1067524384 20893664 6915104
08:35:22 44546 8192000000 9970016992 10372505600 1057093136 11.429 100 26 52 0 1079171952 16656944 5421872
08:37:07 8421 8192000000 9992861472 10381471744 1076954256 11.618 100 40 78 2 1098906896 12695040 9257600
08:38:21 2283 8192000000 10075589680 10463281152 1099940560 11.837 100 75 145 1 1180998416 64884784 16173072
08:39:28 30698 8192000000 10072991376 10476949504 1109794560 11.930 100 49 98 1 1175489232 54028880 11665792
08:41:02 27964 8192000000 10084889312 10494803968 1114188928 11.972 100 26 52 1 1201180512 80646016 6345568
08:42:35 13448 8192000000 10103846928 10516082688 1133929264 12.158 100 45 90 1 1207270960 63560432 9781264
08:43:51 18926 8192000000 10147534928 10563158016 1146484992 12.276 100 49 97 1 1236770368 79870400 10414976
08:45:12 25515 8192000000 10209196256 10623275008 1161032048 12.413 100 59 117 1 1310463344 136439104 12992192
08:46:42 12070 8192000000 10245140432 10655588352 1170880880 12.505 100 54 106 1 1349454464 166724128 11849456
08:48:02 3203 8192000000 10276701984 10685071360 1172928736 12.524 100 68 132 1 1379568768 191601840 15038192
08:49:12 4162 8192000000 10304239696 10708668416 1164352992 12.444 99 76 150 3 1406108240 224406080 17349168
08:50:21 6603 8192000000 10336876000 10745438208 1160299728 12.406 99 68 136 0 1431197408 254520544 16377136
08:51:33 1896 8192000000 10372945728 10788016128 1185165168 12.638 100 74 148 2 1478382336 275116304 18100864
08:52:38 8661 8192000000 10381091920 10790187008 1173519248 12.530 100 73 145 1 1476966592 287163152 16284192
08:53:52 2804 8192000000 10374100864 10781835264 1165825872 12.458 100 68 136 0 1468935440 287034688 16074880
08:54:59 3386 8192000000 10415027472 10837688320 1167514384 12.474 100 79 158 1 1513470912 327089872 18866656
08:56:08 13176 8192000000 10376000480 10815528960 1157540720 12.380 99 45 90 0 1482081568 312629808 11911040
08:57:29 6188 8192000000 10320646704 10742288384 1141954112 12.234 100 46 91 1 1421035840 268069952 11011776
08:58:45 18916 8192000000 10249689680 10739200000 1126574096 12.089 99 30 60 1 1399126976 264785760 7767120
09:00:10 21109 8192000000 10227203424 10697838592 1111818000 11.950 99 27 54 0 1351503872 232634352 7051520
09:02:08 55335 8192000000 10031737168 10459869184 1032346240 11.191 93 19 38 0 1079793904 43208640 4239024
09:04:14 24422 8192000000 10062893520 10505039872 1097405728 11.813 99 21 42 1 1150643312 49088560 4149024
09:05:42 61171 8192000000 10074459104 10531938304 1094633008 11.787 99 12 24 0 1189219088 91608016 2978064
09:07:44 98361 8192000000 10063246032 10498060288 1113370352 11.964 99 30 60 1 1172687760 53285168 6032240
09:10:34 59377 8192000000 10073228256 10505261056 1124492752 12.069 99 26 50 1 1178150272 48850016 4807504
09:12:49 38808 8192000000 10097881216 10536919040 1114355184 11.974 99 38 77 1 1185833536 63819792 7658560
09:14:29 23468 8192000000 10101528816 10537521152 1131902688 12.139 100 37 73 0 1211141232 71579296 7659248
09:16:19 65006 8192000000 10109022176 10559012864 1127259520 12.096 99 11 22 0 1188981408 59498128 2223760
09:18:26 63649 8192000000 10082717840 10526572544 1132287024 12.143 100 13 26 0 1184909008 49685136 2936848
09:20:33 47904 8192000000 10066780976 10500960256 1138477680 12.201 100 23 46 1 1183502736 40441520 4583536
09:22:22 58862 8192000000 10114597520 10551611392 1132717888 12.147 99 21 42 2 1196425936 59414864 4293184
09:24:22 66725 8192000000 10087816656 10540503040 1133077488 12.150 99 14 28 2 1181001968 44743344 3181136
09:27:05 57787 8192000000 10124292048 10602180608 1138688592 12.203 99 13 26 0 1214419808 72858608 2872608
09:29:03 104518 8192000000 10098239056 10572832768 1107161408 11.906 96 16 32 0 1155827008 45447328 3218272
09:31:48 128901 8192000000 10067020384 10517557248 1141106352 12.226 100 19 38 0 1199965456 54802848 4056256
09:34:58 7663 8192000000 10075546080 10508165120 1140960800 12.225 100 35 69 0 1171038896 22623664 7454432
09:36:06 41099 8192000000 10110458560 10574303232 1122733360 12.053 98 13 25 1 1186857872 61721904 2402608
09:39:00 85634 8192000000 10097823488 10585903104 1105758976 11.892 97 23 46 0 1223458448 112814432 4885040
09:41:26 58106 8192000000 10097420288 10553171968 1085801232 11.703 95 20 41 1 1154269664 64691840 3776592
09:43:25 41335 8192000000 10134573072 10567585792 1091024688 11.752 96 20 40 0 1155813760 60444288 4344784
09:45:47 98926 8192000000 10088451568 10565640192 1074711088 11.597 95 30 60 0 1154680624 73300784 6668752
09:48:26 39677 8192000000 10063473344 10508865536 1089939040 11.742 97 23 46 0 1127697808 33288928 4469840
09:50:07 78442 8192000000 10074880896 10499321856 1074339136 11.593 96 22 44 0 1137587232 58580672 4667424
09:52:43 131978 8192000000 10034496320 10479767552 1080631680 11.653 97 18 36 1 1183804656 99407824 3765152
09:56:02 60029 8192000000 10053415088 10504175616 1104055456 11.876 99 7 14 0 1145194640 39772784 1366400
09:58:02 85293 8192000000 10067738272 10551889920 1086159312 11.706 97 15 30 0 1164812560 75231424 3421824
10:00:31 62209 8192000000 10081376864 10525134848 1093432496 11.775 98 14 28 0 1159740688 63189312 3118880
10:02:46 67098 8192000000 10073535376 10535202816 1099388736 11.832 98 18 36 1 1138625120 35753904 3482480
10:04:58 76490 8192000000 10102370352 10550484992 1095952096 11.799 97 19 38 1 1181018416 80509120 4557200
10:07:23 80481 8192000000 10148781616 10636410880 1143218480 12.246 100 35 69 2 1201896432 51617840 7060112
10:09:57 66521 8192000000 10089057776 10545586176 1120631968 12.033 98 18 36 0 1167091360 43196592 3262800
10:12:06 56719 8192000000 10082822768 10534547456 1113529504 11.966 98 18 36 0 1159492576 42399728 3563344
10:14:08 70917 8192000000 10088354896 10603753472 1141275200 12.228 100 27 54 0 1210959696 63858240 5826256
10:16:36 59862 8192000000 10067990496 10550099968 1132315328 12.143 99 17 34 0 1172208016 36544640 3348048
10:18:40 53503 8192000000 10072077376 10532442112 1135453216 12.173 99 12 24 1 1185358112 47076896 2828000
10:20:34 67835 8192000000 10073211552 10542526464 1139675968 12.212 100 11 22 0 1192938272 50855968 2406336
10:22:53 62461 8192000000 10067155776 10533691392 1110576576 11.938 97 17 34 1 1151236960 37483744 3176640
10:24:56 37713 8192000000 10103786544 10566778880 1146017648 12.272 100 45 90 1 1224923200 69408416 9497136
10:26:36 47582 8192000000 10152475360 10589564928 1115429520 11.984 97 32 64 0 1189524816 67291424 6803872
10:28:30 101633 8192000000 10095109712 10589319168 1108359232 11.917 97 26 52 0 1173749696 59961632 5428832
10:31:25 33874 8192000000 10103894432 10576265216 1108530976 11.919 98 16 32 1 1182847232 71138976 3177280
10:33:04 54163 8192000000 10064124448 10526633984 1103406736 11.870 98 19 38 0 1153254496 46257504 3590256
10:35:03 121324 8192000000 10086367088 10541101056 1057082096 11.429 94 24 48 0 1123755872 61431312 5242464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-12-2021 10:40:07 to 12-12-2021 10:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 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:01, from 12-12-2021 10:40:23 to 12-12-2021 10:40:24.

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          234819
MON$OLDEST_ACTIVE               235956
MON$OLDEST_SNAPSHOT             235956
MON$NEXT_TRANSACTION            235956
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                       2038688
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

12.12.21 10:40:26. 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
2021-12-12 10:40:28 No severe PSQL-related problems occured
12.12.21 10:40:29. End of report.