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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-10 10:37:21
DTS_END                         2022-12-10 10:37:21.0630
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/57216
TRN_ID                          211015
ATT_ID                          555
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: 8639.81 35052 1036777
customer order: creation 158.66 505 19039
customer order: refuse 13.56 445 1627
order to supplier: creation 42.07 1407 5048
order to supplier: removal 3.23 195883 387
invoice (draft): creation 42.81 3664 5137
invoice (draft): removal 49.43 1056 5931
invoice accept: apply 39.38 28346 4725
invoice accept: cancel 44.81 1509 5377
customer reserve: creation 3605.43 272 432652
customer reserve: removal 4354.17 116 522500
realization accept: apply 78.23 297 9388
realization accept: cancel 86.71 179 10405
payment from customer: creation 55.24 187 6629
payment from customer: removal 5.32 148 638
payment to supplier: creation 45.78 222 5493
payment to supplier: removal 3.10 165 372
service: total inventory turnovers 3.56 4748 427
service: total monetary turnovers 7.44 437 893
service: aggregate perf. data 0.87 21727 104
service: refresh index statistics 0.04 439719 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2022 10:38:29 to 10-12-2022 10:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9366
TEST_TIME 2 8740
TEST_TIME 3 7771
TEST_TIME 4 7131
TEST_TIME 5 6671
TEST_TIME 6 6623
TEST_TIME 7 6596
TEST_TIME 8 6374
TEST_TIME 9 6512
TEST_TIME 10 6785
TEST_TIME 11 6739
TEST_TIME 12 6700
TEST_TIME 13 6818
TEST_TIME 14 6883
TEST_TIME 15 6759
TEST_TIME 16 6757
TEST_TIME 17 6722
TEST_TIME 18 6775
TEST_TIME 19 6972
TEST_TIME 20 6963
TEST_TIME 21 7192
TEST_TIME 22 7196
TEST_TIME 23 7339
TEST_TIME 24 7401
TEST_TIME 25 7679
TEST_TIME 26 7547
TEST_TIME 27 7826
TEST_TIME 28 8074
TEST_TIME 29 8301
TEST_TIME 30 8893
TEST_TIME 31 9029
TEST_TIME 32 9234
TEST_TIME 33 9696
TEST_TIME 34 10070
TEST_TIME 35 10127
TEST_TIME 36 10254
TEST_TIME 37 10122
TEST_TIME 38 10058
TEST_TIME 39 9842
TEST_TIME 40 9779
TEST_TIME 41 9690
TEST_TIME 42 9665
TEST_TIME 43 9694
TEST_TIME 44 9698
TEST_TIME 45 9581
TEST_TIME 46 9534
TEST_TIME 47 9411
TEST_TIME 48 9315
TEST_TIME 49 9217
TEST_TIME 50 9229
TEST_TIME 51 9065
TEST_TIME 52 8983
TEST_TIME 53 8902
TEST_TIME 54 8900
TEST_TIME 55 8869
TEST_TIME 56 8856
TEST_TIME 57 8880
TEST_TIME 58 8926
TEST_TIME 59 8820
TEST_TIME 60 8769
TEST_TIME 61 8826
TEST_TIME 62 9032
TEST_TIME 63 8996
TEST_TIME 64 8956
TEST_TIME 65 8985
TEST_TIME 66 8922
TEST_TIME 67 8969
TEST_TIME 68 8861
TEST_TIME 69 8831
TEST_TIME 70 8761
TEST_TIME 71 8683
TEST_TIME 72 8658
TEST_TIME 73 8639
TEST_TIME 74 8598
TEST_TIME 75 8543
TEST_TIME 76 8596
TEST_TIME 77 8636
TEST_TIME 78 8595
TEST_TIME 79 8603
TEST_TIME 80 8605
TEST_TIME 81 8617
TEST_TIME 82 8578
TEST_TIME 83 8607
TEST_TIME 84 8694
TEST_TIME 85 8732
TEST_TIME 86 8747
TEST_TIME 87 8736
TEST_TIME 88 8735
TEST_TIME 89 8782
TEST_TIME 90 8806
TEST_TIME 91 8827
TEST_TIME 92 8747
TEST_TIME 93 8701
TEST_TIME 94 8725
TEST_TIME 95 8683
TEST_TIME 96 8621
TEST_TIME 97 8580
TEST_TIME 98 8527
TEST_TIME 99 8611
TEST_TIME 100 8590
TEST_TIME 101 8570
TEST_TIME 102 8540
TEST_TIME 103 8565
TEST_TIME 104 8544
TEST_TIME 105 8548
TEST_TIME 106 8524
TEST_TIME 107 8546
TEST_TIME 108 8543
TEST_TIME 109 8578
TEST_TIME 110 8670
TEST_TIME 111 8704
TEST_TIME 112 8767
TEST_TIME 113 8733
TEST_TIME 114 8783
TEST_TIME 115 8770
TEST_TIME 116 8757
TEST_TIME 117 8741
TEST_TIME 118 8744
TEST_TIME 119 8732
TEST_TIME 120 8671

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2022 10:38:33 to 10-12-2022 10:38:36.

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 1434520 1434520 0 0.00 0 30325 14 0 0
doc_list_biud 1434504 1434504 0 0.00 0 32969 49 0 0
sp_add_invoice_to_stock 4822 4725 97 2.01 32 257032 28347 0 97
sp_cancel_adding_invoice 5377 5377 0 0.00 7 92445 1509 0 0
sp_cancel_client_order 1627 1627 0 0.00 6 13837 445 0 0
sp_cancel_customer_reserve 522500 522500 0 0.00 1 32970 117 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 4712 149 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 4 3651 166 0 0
sp_cancel_supplier_invoice 5942 5931 11 0.18 3 19828 1056 0 11
sp_cancel_supplier_order 387 387 0 0.00 11 1395533 195884 0 0
sp_cancel_write_off 10405 10405 0 0.00 2 12998 180 0 0
sp_client_order 19039 19039 0 0.00 5 28429 506 0 0
sp_customer_reserve 432652 432652 0 0.00 0 54639 272 0 0
sp_fill_shopping_cart 29210 29210 0 0.00 2 27026 93 0 0
sp_fill_shopping_cart_clo_res 432619 432619 0 0.00 0 9700 19 0 0
sp_get_clo_for_invoice 4680 4680 0 0.00 0 33344 309 0 0
sp_kill_cost_storno 16792 16792 0 0.00 0 30310 82 0 0
sp_kill_qstorno_ret_qs2qd 1061723 1061723 0 0.00 0 32966 51 0 0
sp_kill_qty_storno 560294 560294 0 0.00 0 32969 116 0 0
sp_lock_selected_doc 19376 19376 0 0.00 0 6093 7 0 0
sp_make_cost_storno 26190 26190 0 0.00 0 13687 83 0 0
sp_multiply_rows_for_pdistr 26188 26188 0 0.00 0 7380 41 0 0
sp_multiply_rows_for_qdistr 19037 19037 0 0.00 0 14324 191 0 0
sp_pay_from_customer 6629 6629 0 0.00 6 10699 188 0 0
sp_pay_to_supplier 5493 5493 0 0.00 7 16832 223 0 0
sp_payment_common 12120 12120 0 0.00 2 9047 49 0 0
sp_qd_handle_on_cancel_clo 1627 1627 0 0.00 1 13827 375 0 0
sp_qd_handle_on_invoice_upd_sts 10057 10057 0 0.00 0 18547 563 0 0
sp_qd_handle_on_reserve_upd_sts 9387 9387 0 0.00 0 13172 74 0 0
sp_reserve_write_off 9388 9388 0 0.00 9 16884 298 0 0
sp_supplier_invoice 5137 5137 0 0.00 9 89052 3665 0 0
sp_supplier_order 5048 5048 0 0.00 9 44155 1407 0 0
srv_aggregate_perf_data 104 104 0 0.00 2811 260913 21727 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 736 103120 31624 0 0
srv_find_qd_qs_mism 990853 990853 0 0.00 0 17654 10 0 0
srv_make_invnt_saldo 427 427 0 0.00 80 62923 4749 0 0
srv_make_money_saldo 893 893 0 0.00 1 17526 438 0 0
srv_recalc_idx_stat 5 5 0 0.00 227405 550118 439719 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 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4729 4729 0 0.00 0 20990 1056 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 7085 598 0 0
t$perf-norm:sp_cancel_client_order 1627 1627 0 0.00 0 1845 28 0 0
t$perf-norm:sp_cancel_customer_reserve 601 601 0 0.00 0 778 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 1212 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 736 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 3225 49 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 105300 8273 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 4162 24 0 0
t$perf-norm:sp_client_order 19040 19040 0 0.00 0 6021 42 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 2013 17 0 0
t$perf-norm:sp_pay_from_customer 6630 6630 0 0.00 0 5606 21 0 0
t$perf-norm:sp_pay_to_supplier 5493 5493 0 0.00 0 4865 22 0 0
t$perf-norm:sp_reserve_write_off 9389 9389 0 0.00 0 5956 24 0 0
t$perf-norm:sp_supplier_invoice 5137 5137 0 0.00 0 2984 32 0 0
t$perf-norm:sp_supplier_order 5048 5048 0 0.00 0 7842 38 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 280 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 3635 60 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 1648 16 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 2028 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 193 47 0 0
v_add_invoice_to_stock 9546 9352 194 2.03 0 19560 28 0 194
v_all_customers 34818 34818 0 0.00 0 70 4 0 0
v_all_suppliers 28648 28648 0 0.00 0 110 5 0 0
v_all_wares 38070 38070 0 0.00 0 165 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 536 15 0 0
v_cancel_client_order 3254 3254 0 0.00 0 1961 10 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 4268 15 0 0
v_cancel_customer_reserve 1202 1202 0 0.00 0 2196 13 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 2443 20 0 0
v_cancel_supplier_invoice 766 744 22 2.87 0 14895 46 0 22
v_cancel_supplier_order 774 774 0 0.00 0 3514 21 0 0
v_cancel_write_off 1830 1830 0 0.00 0 977 26 0 0
v_max_id_clo_ord 5043 5043 0 0.00 0 2265 6 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 2927 17 0 0
v_max_id_ord_sup 5130 5130 0 0.00 0 6339 11 0 0
v_max_non_paid_invoice 1341 1341 0 0.00 0 3036 10 0 0
v_max_non_paid_realizn 3502 3502 0 0.00 0 296 7 0 0
v_min_id_clo_ord 5043 5043 0 0.00 0 1202 8 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4520 13 0 0
v_min_id_ord_sup 5130 5130 0 0.00 0 8236 11 0 0
v_min_non_paid_invoice 5492 5492 0 0.00 0 7486 22 0 0
v_min_non_paid_realizn 6628 6628 0 0.00 0 7523 19 0 0
v_our_firm 9502 9502 0 0.00 0 186 5 0 0
v_reserve_write_off 18774 18774 0 0.00 0 16679 13 0 0
x_lock_depdocs_on_canc_invoice 10936 10936 0 0.00 0 10397 133 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 5182 418 0 0
x_make_qstorno_1000_1200 5048 5048 0 0.00 3 44139 1229 0 0
x_make_qstorno_1200_2000 5137 5137 0 0.00 4 80814 3385 0 0
x_make_qstorno_2100_3300 432650 432650 0 0.00 0 54609 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2022 10:38:37 to 10-12-2022 10:38:41.

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:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 39884 8192000000 9732027344 10030948352 801928080 8.916 100 87 174 0 830799488 7459104 21412304
08:10:09 21230 8192000000 9766982016 10072764416 838777088 9.287 100 91 182 0 873148704 12371808 21999808
08:11:36 11579 8192000000 9789458304 10110119936 864482384 9.545 100 82 164 0 904640288 20701296 19456608
08:12:51 9714 8192000000 9848134608 10178428928 907265744 9.970 100 83 166 0 958842576 33638960 17937872
08:14:04 8724 8192000000 9903134224 10243764224 940738400 10.300 100 75 150 0 1013542176 56942336 15861440
08:15:17 2963 8192000000 9957304624 10306998272 984450048 10.728 100 89 173 0 1069772976 67318784 18004144
08:16:23 1247 8192000000 9982141568 10340552704 1009251600 10.968 100 95 179 0 1102300432 74579104 18469728
08:17:27 6773 8192000000 10000508512 10371510272 1033160384 11.199 100 89 167 1 1123152048 73285040 16706624
08:18:38 1364 8192000000 10026874864 10401976320 1053489104 11.394 100 96 190 0 1147344480 74167904 19687472
08:19:41 978 8192000000 10025065712 10400714752 1066455616 11.518 100 95 188 2 1150242048 64758672 19027760
08:20:44 3297 8192000000 10037424384 10416791552 1072475504 11.576 100 81 146 1 1159081680 72900864 13705312
08:21:49 2387 8192000000 10037462176 10419036160 1083688832 11.683 100 93 172 2 1162281856 60303472 18289552
08:22:52 5755 8192000000 10046057584 10432630784 1085440400 11.699 100 86 166 1 1175829056 73850064 16538592
08:23:59 1183 8192000000 10076338192 10466275328 1093875808 11.779 100 94 178 0 1196473248 84471216 18126224
08:25:02 1208 8192000000 10083845008 10465984512 1095191408 11.792 100 97 182 2 1199456272 85671728 18593136
08:26:04 1234 8192000000 10080318544 10466152448 1098112768 11.820 100 99 186 4 1201450896 83877568 19460560
08:27:06 2450 8192000000 10046752400 10439409664 1102589040 11.862 100 77 144 5 1167598960 48350592 16659328
08:28:09 2339 8192000000 10045875344 10440208384 1101192176 11.849 100 75 137 1 1169600128 53689584 14718368
08:29:12 1390 8192000000 10073174864 10458492928 1096824096 11.807 100 97 187 0 1200169072 84246832 19098144
08:30:15 1140 8192000000 10055376640 10446462976 1074582272 11.596 99 94 179 0 1174521600 80681008 19258320
08:31:16 977 8192000000 10044596384 10434306048 1081806320 11.665 100 88 172 1 1170191440 70511040 17874080
08:32:19 978 8192000000 10026244736 10408878080 1067335376 11.527 100 91 175 1 1145021472 60225424 17460672
08:33:21 3789 8192000000 9998374912 10378891264 1055919600 11.417 99 76 129 4 1113772272 44698464 13154208
08:34:26 1116 8192000000 10003363632 10379505664 1029817696 11.167 100 94 172 4 1122439824 74434832 18187296
08:35:28 1190 8192000000 9979454512 10352054272 1034247408 11.209 100 91 169 0 1104081088 52747360 17086320
08:36:30 1082 8192000000 9993567440 10373902336 1045012224 11.313 100 86 167 2 1113079888 52298816 15768848
08:37:36 1790 8192000000 10008618192 10384519168 1041031680 11.275 100 90 168 2 1135203984 75661776 18510528
08:38:48 3097 8192000000 10005746384 10386714624 1051762832 11.378 100 73 147 0 1123050064 56809008 14478224
08:39:55 1227 8192000000 10029400528 10415124480 1064145744 11.496 100 89 171 0 1146515536 64528752 17841040
08:41:02 792 8192000000 10035143584 10418003968 1072666688 11.578 100 75 144 1 1152565856 65386448 14512720
08:42:08 5715 8192000000 10083684240 10473189376 1097008832 11.809 100 77 149 0 1204062992 91136144 15918016
08:43:18 1368 8192000000 10119623728 10501484544 1101586656 11.853 100 93 171 1 1238730176 117219136 19924384
08:44:25 5608 8192000000 10116885936 10511572992 1116158880 11.991 100 71 138 0 1239809504 108904864 14745760
08:45:36 4709 8192000000 10166703616 10564825088 1130508288 12.126 100 72 139 0 1283386976 136960512 15918176
08:46:43 971 8192000000 10184112256 10591617024 1133269728 12.152 100 94 179 0 1300986272 148532048 19184496
08:47:49 1139 8192000000 10200893152 10604822528 1150847184 12.317 100 73 142 2 1314008352 147739248 15421920
08:48:54 2093 8192000000 10255285056 10653372416 1169798784 12.495 100 90 170 1 1369261184 181346992 18115408
08:49:59 1191 8192000000 10296484704 10693115904 1172383312 12.519 100 96 181 3 1402446768 210521264 19542192
08:51:05 1989 8192000000 10302615120 10724110336 1183945392 12.627 100 78 148 0 1418325648 217988384 16391872
08:52:11 1870 8192000000 10343459088 10749181952 1194390736 12.724 100 82 151 0 1451252400 240347328 16514336
08:53:22 1038 8192000000 10419247488 10819817472 1205263408 12.825 100 96 188 0 1527712384 301767808 20681168
08:54:28 1510 8192000000 10422239312 10827460608 1208747792 12.857 100 94 180 0 1537079568 306898656 21433120
08:55:34 1471 8192000000 10480219888 10896539648 1215949152 12.924 100 98 192 0 1586060288 348086592 22024544
08:56:39 1317 8192000000 10533724880 10953711616 1225129440 13.009 100 98 187 1 1638317920 390865120 22323360
08:57:43 730 8192000000 10513327152 10957950976 1218888896 12.951 100 98 192 0 1622353408 380809024 22655488
08:58:47 1439 8192000000 10545921568 10972442624 1212169456 12.889 100 96 185 1 1649431968 415579712 21682800
08:59:56 926 8192000000 10565120576 10994257920 1223052112 12.990 100 97 185 0 1668800704 423667968 22080624
09:01:04 1798 8192000000 10581229312 11027251200 1226822384 13.025 100 79 156 0 1692245264 446448080 18974800
09:02:14 3314 8192000000 10567946560 11042328576 1216260816 12.927 100 64 126 0 1676521072 444009808 16250448
09:03:29 860 8192000000 10546560608 10987163648 1191303072 12.695 100 94 178 2 1646460640 433713136 21444432
09:04:34 746 8192000000 10583739840 11039793152 1193388608 12.715 100 97 188 1 1689010208 473089216 22532384
09:05:38 9738 8192000000 10537379920 11031060480 1197609680 12.754 100 57 113 0 1638853552 426859232 14384640
09:06:53 4436 8192000000 10486412208 10975113216 1195551184 12.735 100 61 122 0 1589106672 378481280 15074208
09:07:58 30836 8192000000 10426632000 10873348096 1169722800 12.494 99 33 66 1 1522294784 343747536 8824448
09:10:16 15102 8192000000 10219159120 10769424384 1124835936 12.073 98 34 68 0 1359067216 225623472 8607808
09:11:32 28568 8192000000 10129530336 10589712384 1079969552 11.647 94 50 100 0 1186544128 95344240 11230336
09:13:25 42827 8192000000 10108249904 10563031040 1120331312 12.030 99 21 43 0 1209165488 84270096 4564080
09:15:12 69346 8192000000 10114698432 10556370944 1121400016 12.040 99 34 66 0 1201567520 73211200 6956304
09:17:38 37924 8192000000 10113032976 10556637184 1099597440 11.834 96 32 60 0 1180395360 74574352 6223568
09:19:18 63603 8192000000 10115821536 10593923072 1129443856 12.116 99 33 65 1 1219084544 83329072 6311616
09:21:23 69458 8192000000 10085623104 10566975488 1135156960 12.170 100 20 36 0 1214546240 75575696 3813584
09:23:38 41492 8192000000 10043057024 10490494976 1130372320 12.125 100 9 16 0 1157917632 26193984 1351328
09:25:20 51482 8192000000 10095956656 10542931968 1137464656 12.192 100 26 48 0 1199619376 57437584 4717136
09:27:13 96260 8192000000 10067825984 10497720320 1135895312 12.177 100 47 94 2 1220766944 73797216 11074416
09:30:09 1361 8192000000 10078845056 10519453696 1137120528 12.188 100 55 107 0 1187118448 38582048 11415872
09:31:13 53663 8192000000 10105181984 10573836288 1113289360 11.964 98 26 52 0 1196371760 77421232 5661168
09:33:09 59756 8192000000 10065464336 10575577088 1109471840 11.927 98 14 29 0 1173552352 61155168 2925344
09:35:13 50894 8192000000 10082064688 10558517248 1117066512 11.999 99 29 58 0 1210105920 87064032 5975376
09:37:10 61054 8192000000 10154927632 10642485248 1128050240 12.103 99 27 52 1 1258608128 125159680 5398208
09:39:22 103117 8192000000 10077839728 10537422848 1136679936 12.184 99 30 58 1 1199086864 56236592 6170336
09:42:06 52870 8192000000 10082368288 10525978624 1120626752 12.033 98 44 87 1 1182970432 53859536 8484144
09:44:03 21613 8192000000 10129923248 10578481152 1135436224 12.173 100 50 99 0 1230138576 84940064 9762288
09:45:33 76831 8192000000 10096399776 10552201216 1117428944 12.003 98 51 102 2 1184107136 55863712 10814480
09:48:04 27915 8192000000 10126122560 10569035776 1144278800 12.256 100 42 83 0 1199606224 46773728 8553696
09:49:32 96112 8192000000 10135439808 10632880128 1146647456 12.278 100 24 49 0 1262684384 110626832 5410096
09:52:08 69353 8192000000 10140500464 10600132608 1149651456 12.306 100 17 34 0 1260965824 107132448 4181920
09:54:40 40786 8192000000 10106704864 10570280960 1157925600 12.384 100 23 42 2 1206452864 44280448 4246816
09:56:21 58218 8192000000 10130574704 10568744960 1098380912 11.822 94 19 38 0 1156739408 54386720 3971776
09:58:28 41530 8192000000 10134668800 10584956928 1119342912 12.021 97 25 50 0 1210079088 85413520 5322656
10:00:09 11359 8192000000 10148152544 10584231936 1123471216 12.060 98 39 78 0 1244620256 113105792 8043248
10:01:21 101194 8192000000 10117247888 10600194048 1126149312 12.085 98 14 28 0 1221417728 92235504 3032912
10:04:05 27886 8192000000 10106568304 10548088832 1139584928 12.212 100 41 82 0 1205130976 57337776 8208272
10:05:37 30094 8192000000 10145604368 10589163520 1140103984 12.217 100 54 106 0 1253641952 102710400 10827568
10:07:34 15194 8192000000 10108647136 10542657536 1092853952 11.770 97 46 89 0 1171523344 69613824 9055568
10:08:52 83363 8192000000 10071769248 10507681792 1100301808 11.841 99 30 55 2 1171628048 65088608 6237632
10:11:51 83266 8192000000 10059697088 10539585536 1082249296 11.669 97 7 14 0 1141958528 58491760 1217472
10:14:37 15745 8192000000 10118620960 10616434688 1120781552 12.034 100 6 12 0 1213356832 91495584 1079696
10:15:53 57492 8192000000 10108978720 10610429952 1115299728 11.983 98 20 39 0 1208541408 88975136 4266544
10:17:54 20309 8192000000 10146876336 10591649792 1129751456 12.119 99 30 60 0 1228044976 91146720 7146800
10:19:19 24408 8192000000 10176791104 10602909696 1129028176 12.112 98 35 67 1 1248815952 112282608 7505168
10:20:50 38226 8192000000 10194972576 10644828160 1136558816 12.183 99 30 60 0 1279784016 136115776 7109424
10:22:35 32121 8192000000 10238727792 10675736576 1117808016 12.006 97 38 74 0 1296600336 170558848 8233472
10:24:14 40102 8192000000 10266723280 10701475840 1155176032 12.358 99 32 64 0 1351039344 188183344 7679968
10:26:03 20687 8192000000 10312355760 10769838080 1147907920 12.290 99 39 78 0 1382449408 225551584 8989904
10:27:33 25403 8192000000 10218671584 10784530432 1127627472 12.099 99 13 26 0 1361345840 230290432 3427936
10:30:37 91388 8192000000 10101157040 10623279104 1131534960 12.136 100 11 22 0 1190252192 56909920 1807312
10:33:12 43462 8192000000 10100985712 10555330560 1126060816 12.084 98 27 50 0 1175278224 43534160 5683248
10:34:55 89585 8192000000 10116866320 10558128128 1122394112 12.050 98 32 64 0 1178844848 49265200 7185536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-12-2022 10:38:43 to 10-12-2022 10:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          210450
MON$OLDEST_ACTIVE               211435
MON$OLDEST_SNAPSHOT             211435
MON$NEXT_TRANSACTION            211435
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                       2067360
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             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

10.12.22 10:39:03. 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-12-10 10:39:06 No severe PSQL-related problems occured
10.12.22 10:39:07. End of report.