Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2022 01: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 14889688 kB
MemAvailable 30064736 kB
Buffers 0 kB
Cached 16792712 kB
SwapCached 3272 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-23 01:38:33
DTS_END                         2022-12-23 01:38:33.9790
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(57:17)
IP                              ::1/36106
TRN_ID                          246950
ATT_ID                          643
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: 9745.48 21130 1169457
customer order: creation 178.18 372 21381
customer order: refuse 15.51 380 1861
order to supplier: creation 47.36 1281 5683
order to supplier: removal 3.52 101174 422
invoice (draft): creation 46.79 3221 5615
invoice (draft): removal 56.04 794 6725
invoice accept: apply 43.55 16701 5226
invoice accept: cancel 50.30 795 6036
customer reserve: creation 4040.47 163 484856
customer reserve: removal 4963.96 55 595675
realization accept: apply 84.83 202 10179
realization accept: cancel 81.43 110 9772
payment from customer: creation 62.10 143 7452
payment from customer: removal 5.82 76 698
payment to supplier: creation 49.58 131 5949
payment to supplier: removal 3.53 123 423
service: total inventory turnovers 5.03 1305 604
service: total monetary turnovers 6.73 30 808
service: aggregate perf. data 0.73 26586 88
service: refresh index statistics 0.03 268964 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2022 01:39:08 to 23-12-2022 01:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2870
TEST_TIME 2 3728
TEST_TIME 3 2761
TEST_TIME 4 2918
TEST_TIME 5 3533
TEST_TIME 6 3539
TEST_TIME 7 4908
TEST_TIME 8 5056
TEST_TIME 9 5149
TEST_TIME 10 5395
TEST_TIME 11 5949
TEST_TIME 12 5762
TEST_TIME 13 6027
TEST_TIME 14 6055
TEST_TIME 15 6729
TEST_TIME 16 6952
TEST_TIME 17 7268
TEST_TIME 18 7475
TEST_TIME 19 7507
TEST_TIME 20 8169
TEST_TIME 21 8175
TEST_TIME 22 8117
TEST_TIME 23 8451
TEST_TIME 24 8568
TEST_TIME 25 8543
TEST_TIME 26 8576
TEST_TIME 27 8470
TEST_TIME 28 8707
TEST_TIME 29 9215
TEST_TIME 30 10390
TEST_TIME 31 10294
TEST_TIME 32 10166
TEST_TIME 33 10397
TEST_TIME 34 10452
TEST_TIME 35 10372
TEST_TIME 36 10376
TEST_TIME 37 10169
TEST_TIME 38 10175
TEST_TIME 39 10092
TEST_TIME 40 10260
TEST_TIME 41 10367
TEST_TIME 42 10241
TEST_TIME 43 10222
TEST_TIME 44 10162
TEST_TIME 45 10027
TEST_TIME 46 9975
TEST_TIME 47 9907
TEST_TIME 48 10062
TEST_TIME 49 10082
TEST_TIME 50 9981
TEST_TIME 51 9872
TEST_TIME 52 9922
TEST_TIME 53 9780
TEST_TIME 54 9774
TEST_TIME 55 9751
TEST_TIME 56 9671
TEST_TIME 57 9657
TEST_TIME 58 9606
TEST_TIME 59 9572
TEST_TIME 60 9488
TEST_TIME 61 9477
TEST_TIME 62 9601
TEST_TIME 63 9608
TEST_TIME 64 9717
TEST_TIME 65 9719
TEST_TIME 66 9702
TEST_TIME 67 9759
TEST_TIME 68 9801
TEST_TIME 69 9784
TEST_TIME 70 9856
TEST_TIME 71 9927
TEST_TIME 72 9895
TEST_TIME 73 9992
TEST_TIME 74 9983
TEST_TIME 75 9875
TEST_TIME 76 9810
TEST_TIME 77 9802
TEST_TIME 78 9792
TEST_TIME 79 9764
TEST_TIME 80 9726
TEST_TIME 81 9707
TEST_TIME 82 9704
TEST_TIME 83 9637
TEST_TIME 84 9604
TEST_TIME 85 9573
TEST_TIME 86 9589
TEST_TIME 87 9608
TEST_TIME 88 9620
TEST_TIME 89 9612
TEST_TIME 90 9644
TEST_TIME 91 9722
TEST_TIME 92 9743
TEST_TIME 93 9766
TEST_TIME 94 9745
TEST_TIME 95 9726
TEST_TIME 96 9735
TEST_TIME 97 9765
TEST_TIME 98 9825
TEST_TIME 99 9828
TEST_TIME 100 9841
TEST_TIME 101 9755
TEST_TIME 102 9743
TEST_TIME 103 9720
TEST_TIME 104 9745
TEST_TIME 105 9703
TEST_TIME 106 9672
TEST_TIME 107 9642
TEST_TIME 108 9594
TEST_TIME 109 9618
TEST_TIME 110 9614
TEST_TIME 111 9608
TEST_TIME 112 9615
TEST_TIME 113 9651
TEST_TIME 114 9634
TEST_TIME 115 9647
TEST_TIME 116 9679
TEST_TIME 117 9732
TEST_TIME 118 9730
TEST_TIME 119 9779
TEST_TIME 120 9749

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2022 01:39:12 to 23-12-2022 01:39:15.

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 1617634 1617634 0 0.00 0 28354 6 0 0
doc_list_biud 1617633 1617633 0 0.00 0 46765 26 0 0
sp_add_invoice_to_stock 5340 5226 114 2.13 32 168202 16701 0 114
sp_cancel_adding_invoice 6036 6036 0 0.00 4 73918 795 0 0
sp_cancel_client_order 1861 1861 0 0.00 4 20740 380 0 0
sp_cancel_customer_reserve 595675 595675 0 0.00 0 21378 56 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 6775 76 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 14478 123 0 0
sp_cancel_supplier_invoice 6739 6725 14 0.20 2 19236 794 0 14
sp_cancel_supplier_order 422 422 0 0.00 6 928060 101174 0 0
sp_cancel_write_off 9772 9772 0 0.00 1 11367 110 0 0
sp_client_order 21381 21381 0 0.00 3 30546 372 0 0
sp_customer_reserve 484856 484856 0 0.00 0 73749 163 0 0
sp_fill_shopping_cart 32679 32679 0 0.00 1 14764 37 0 0
sp_fill_shopping_cart_clo_res 484850 484850 0 0.00 0 16767 15 0 0
sp_get_clo_for_invoice 5220 5220 0 0.00 0 29836 344 0 0
sp_kill_cost_storno 16929 16929 0 0.00 0 14212 53 0 0
sp_kill_qstorno_ret_qs2qd 1208269 1208269 0 0.00 0 24213 30 0 0
sp_kill_qty_storno 635889 635889 0 0.00 0 46759 66 0 0
sp_lock_selected_doc 21374 21374 0 0.00 0 8470 9 0 0
sp_make_cost_storno 28799 28799 0 0.00 0 28353 65 0 0
sp_multiply_rows_for_pdistr 28799 28799 0 0.00 0 17034 28 0 0
sp_multiply_rows_for_qdistr 21381 21381 0 0.00 0 14661 154 0 0
sp_pay_from_customer 7452 7452 0 0.00 4 28697 143 0 0
sp_pay_to_supplier 5949 5949 0 0.00 4 19280 132 0 0
sp_payment_common 13401 13401 0 0.00 1 18253 47 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 20731 342 0 0
sp_qd_handle_on_invoice_upd_sts 11255 11255 0 0.00 0 46751 326 0 0
sp_qd_handle_on_reserve_upd_sts 10179 10179 0 0.00 0 18274 63 0 0
sp_reserve_write_off 10179 10179 0 0.00 5 28361 202 0 0
sp_supplier_invoice 5615 5615 0 0.00 6 76471 3221 0 0
sp_supplier_order 5683 5683 0 0.00 5 59428 1281 0 0
srv_aggregate_perf_data 88 88 0 0.00 2926 477163 26587 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1491 116586 42080 0 0
srv_find_qd_qs_mism 1119502 1119502 0 0.00 0 17079 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 42 155486 1306 0 0
srv_make_money_saldo 808 808 0 0.00 0 2847 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 182562 345361 268964 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5226 5226 0 0.00 0 19730 169 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 21416 144 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 19010 25 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 2704 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 4767 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 163 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 1914 19 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 23699 1765 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 715 4 0 0
t$perf-norm:sp_client_order 21381 21381 0 0.00 0 7830 18 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 529 3 0 0
t$perf-norm:sp_pay_from_customer 7452 7452 0 0.00 0 10208 12 0 0
t$perf-norm:sp_pay_to_supplier 5949 5949 0 0.00 0 19405 12 0 0
t$perf-norm:sp_reserve_write_off 10179 10179 0 0.00 0 6233 12 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 7541 20 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 19480 22 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 174 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 433 7 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 3432 32 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 843 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 120 33 0 0
v_add_invoice_to_stock 10666 10438 228 2.13 0 6789 21 0 228
v_all_customers 39302 39302 0 0.00 0 106 1 0 0
v_all_suppliers 31460 31460 0 0.00 0 45 1 0 0
v_all_wares 42762 42762 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1878 10 0 0
v_cancel_client_order 3722 3722 0 0.00 0 3271 8 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 3596 8 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 4515 10 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1322 8 0 0
v_cancel_supplier_invoice 898 870 28 3.11 0 1797 16 0 28
v_cancel_supplier_order 844 844 0 0.00 0 459 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 7569 31 0 0
v_max_id_clo_ord 5683 5683 0 0.00 0 391 1 0 0
v_max_id_clo_res 999 999 0 0.00 0 3420 12 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 4923 4 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 402 3 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 6743 8 0 0
v_min_id_clo_ord 5683 5683 0 0.00 0 3984 6 0 0
v_min_id_clo_res 999 999 0 0.00 0 8413 22 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 3389 6 0 0
v_min_non_paid_invoice 5949 5949 0 0.00 0 17794 30 0 0
v_min_non_paid_realizn 7452 7452 0 0.00 0 18249 43 0 0
v_our_firm 10532 10532 0 0.00 0 22 1 0 0
v_reserve_write_off 20358 20358 0 0.00 0 3161 6 0 0
x_lock_depdocs_on_canc_invoice 12326 12326 0 0.00 0 17862 112 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 18419 485 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 59387 1209 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 2 69363 3078 0 0
x_make_qstorno_2100_3300 484855 484855 0 0.00 0 73746 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2022 01:39:16 to 23-12-2022 01:39:20.

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
23:08:37 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 27551 8192000000 9911694256 10221645824 990526640 10.787 100 88 175 0 1018750304 5812688 22410976
23:11:11 17152 8192000000 9949771184 10273038336 1035060144 11.217 100 88 176 0 1066151584 8282976 22808464
23:12:47 15044 8192000000 9999892448 10336436224 1076239280 11.612 100 78 157 0 1105556640 9864144 19453216
23:14:07 6156 8192000000 10036398800 10387320832 1123296400 12.058 100 74 147 1 1151572752 10124064 18152288
23:15:21 6518 8192000000 10086383344 10447917056 1178044400 12.572 100 75 150 0 1206811744 11802912 16964432
23:16:30 3687 8192000000 10134269872 10506063872 1226308896 13.020 100 76 150 0 1255192880 12808704 16075280
23:17:35 1505 8192000000 10173379088 10549817344 1261389312 13.343 100 91 178 2 1295556432 14449952 19717168
23:18:38 1891 8192000000 10193247792 10576801792 1285385904 13.562 100 82 162 0 1316710800 13772384 17552512
23:19:42 44240 8192000000 10191696208 10592018432 1297422272 13.672 100 48 95 0 1315959792 7971456 10566064
23:21:27 3784 8192000000 10195234000 10606927872 1307481344 13.763 100 26 52 1 1321114464 7272240 6360880
23:22:35 8085 8192000000 10195224304 10614919168 1314299872 13.825 100 22 44 1 1326146640 6927216 4919552
23:23:46 10619 8192000000 10212879232 10628096000 1324264368 13.915 100 13 26 0 1333135088 6138704 2732016
23:25:05 38788 8192000000 10234236416 10641915904 1334275456 14.006 100 56 111 0 1352803280 6700192 11827632
23:26:45 10242 8192000000 10217905888 10636398592 1317594848 13.855 99 27 52 2 1329545408 6130048 5820512
23:27:55 5240 8192000000 10205568960 10614374400 1297070656 13.669 98 22 44 0 1307658144 5420592 5166896
23:29:04 9428 8192000000 10160469168 10563969024 1225366528 13.011 95 16 32 2 1234205728 5469440 3369760
23:30:14 13952 8192000000 10153844688 10566496256 1230442064 13.058 96 13 26 0 1238466192 5362560 2661568
23:31:28 4163 8192000000 10132853120 10520997888 1221147968 12.972 98 35 70 0 1236660784 8028432 7484384
23:32:34 8739 8192000000 10143788944 10542325760 1248528896 13.225 100 26 52 0 1260839760 6359136 5951728
23:33:48 18078 8192000000 10173288016 10574995456 1263617584 13.363 99 29 59 0 1275724528 4927776 7179168
23:35:07 19335 8192000000 10171015536 10584997888 1288716944 13.593 100 32 65 0 1301968304 5960688 7290672
23:36:27 4487 8192000000 10195253312 10601205760 1299649648 13.692 100 37 72 1 1314455360 6626144 8179568
23:37:35 7738 8192000000 10193360432 10610180096 1305662544 13.747 100 23 47 0 1321487168 10483984 5340640
23:38:52 25922 8192000000 10231469952 10645688320 1332456016 13.989 100 30 60 1 1345280080 5794048 7030016
23:40:22 87078 8192000000 10249566688 10667274240 1326787200 13.938 98 32 64 0 1342299264 7602208 7909856
23:43:04 6105 8192000000 10258829104 10676518912 1358737616 14.226 100 54 105 0 1382204000 11412048 12054336
23:44:25 27686 8192000000 10289559248 10709319680 1368094288 14.310 100 36 72 0 1391356368 14241664 9020416
23:46:16 14502 8192000000 10294140624 10720915456 1385371632 14.465 100 41 82 0 1406405552 11763536 9270384
23:47:36 7338 8192000000 10319362304 10736357376 1395864096 14.558 100 72 142 1 1429097760 15994496 17239168
23:48:46 17981 8192000000 10327623520 10751492096 1407764272 14.664 100 43 86 0 1435397056 17066064 10566720
23:50:12 1804 8192000000 10324412608 10744053760 1401738512 14.610 100 63 124 0 1432522336 15069120 15714704
23:51:18 4879 8192000000 10316832224 10737840128 1389644224 14.503 99 61 120 0 1426426176 23098928 13683024
23:52:27 3246 8192000000 10323878496 10734018560 1386525904 14.475 99 76 150 1 1427842832 22420672 18896256
23:53:52 1489 8192000000 10309856928 10712391680 1361506080 14.251 100 87 176 0 1411058672 28479600 21072992
23:54:57 2975 8192000000 10319457168 10732179456 1376619904 14.386 100 61 121 0 1424093936 31860672 15613360
23:56:04 2519 8192000000 10316870656 10723217408 1369235632 14.320 100 80 158 0 1422553184 34342336 18975216
23:57:14 7302 8192000000 10285082016 10687823872 1332001600 13.985 100 61 121 0 1390631440 42427008 16202832
23:58:27 1634 8192000000 10330368192 10730778624 1367627920 14.306 100 86 172 0 1431867424 41412752 22826752
23:59:35 9058 8192000000 10308063984 10727972864 1354687680 14.190 99 55 109 1 1408538560 38394816 15456064
00:01:12 7875 8192000000 10320438320 10736889856 1373077136 14.355 100 62 122 0 1429667616 40965936 15624544
00:02:27 6272 8192000000 10331374240 10762850304 1392671376 14.530 100 39 78 0 1439520864 36514864 10334624
00:03:38 8965 8192000000 10331258832 10763554816 1392215872 14.526 100 39 76 0 1440428144 38194160 10018112
00:04:54 11135 8192000000 10339790016 10774093824 1393962176 14.541 100 32 64 1 1443019648 40658704 8398768
00:06:07 28915 8192000000 10339980208 10787946496 1392892096 14.532 100 27 54 0 1454059280 53137120 8030064
00:07:58 42868 8192000000 10294884896 10764791808 1364490128 14.278 99 15 30 1 1409985504 41468544 4026832
00:10:10 15811 8192000000 10306379584 10741137408 1387520192 14.484 100 38 76 0 1407159696 11005616 8633888
00:11:26 52010 8192000000 10299962864 10765500416 1377099920 14.391 99 8 17 0 1398540512 19628304 1812288
00:13:20 70426 8192000000 10303850384 10755735552 1363160896 14.266 98 19 37 0 1377507648 10032752 4314000
00:15:32 89264 8192000000 10320669968 10771234816 1401321024 14.607 100 32 62 1 1430695360 22183376 7190960
00:18:09 75876 8192000000 10310426544 10776252416 1402112944 14.614 100 17 34 0 1425183760 19325312 3745504
00:20:26 36411 8192000000 10313806352 10761777152 1392096256 14.525 99 25 49 0 1407719584 10495728 5127600
00:22:04 94244 8192000000 10331959760 10789658624 1421153824 14.783 100 13 26 0 1438102320 14148208 2800288
00:24:42 21344 8192000000 10310948224 10761056256 1402592912 14.618 100 31 62 1 1420788064 10686880 7508272
00:26:07 32357 8192000000 10314448976 10777456640 1404360816 14.634 100 20 40 0 1418768560 10346384 4061360
00:27:42 25574 8192000000 10314335632 10776797184 1400495744 14.599 99 24 48 1 1415317840 9624624 5197472
00:29:08 31555 8192000000 10304723056 10767175680 1408259584 14.668 100 5 10 0 1418566416 9260736 1046096
00:30:39 37659 8192000000 10303129072 10777722880 1406377312 14.652 100 8 16 0 1423375152 15144080 1853760
00:32:17 44276 8192000000 10313518304 10774466560 1408031376 14.666 100 21 42 1 1423716800 10350704 5334720
00:34:21 51725 8192000000 10302534176 10764406784 1385719312 14.468 98 22 43 0 1399404880 8747760 4937808
00:36:14 102833 8192000000 10292801024 10762776576 1365755648 14.289 97 11 22 0 1380549728 12258400 2535680
00:39:02 58801 8192000000 10294005104 10758901760 1378845264 14.406 98 17 34 1 1393550912 10608832 4096816
00:41:11 44608 8192000000 10291158304 10749665280 1342481664 14.080 96 19 38 0 1359354048 12484176 4388208
00:42:56 63905 8192000000 10284150688 10731261952 1309996480 13.786 94 20 40 0 1329317664 15016160 4305024
00:45:00 25763 8192000000 10249932832 10688098304 1295422064 13.654 95 19 38 0 1308352480 9071024 3859392
00:46:27 59188 8192000000 10278096464 10717667328 1358361616 14.223 100 26 52 0 1375301776 11121632 5818528
00:48:26 52741 8192000000 10274240720 10738577408 1342063568 14.076 98 16 32 0 1362411968 16725504 3622896
00:50:44 81377 8192000000 10280829200 10738384896 1341648288 14.072 98 20 40 0 1368462304 22374080 4439936
00:53:18 94583 8192000000 10278851584 10726502400 1356457296 14.206 99 42 84 0 1374123664 8290192 9376176
00:56:02 31952 8192000000 10244277024 10701471744 1347224736 14.123 100 13 27 0 1358512448 8679184 2608528
00:57:39 47828 8192000000 10257567088 10708926464 1357781024 14.217 100 17 34 0 1370036464 8189488 4065952
00:59:35 35953 8192000000 10254478960 10698424320 1315892144 13.839 97 19 38 0 1330027936 9318256 4817536
01:01:11 54712 8192000000 10269057728 10722402304 1369640624 14.324 100 15 28 0 1379945296 7111312 3193360
01:03:07 41696 8192000000 10269747472 10726277120 1358037728 14.220 99 24 48 0 1375034448 11548800 5447920
01:04:49 42991 8192000000 10272997632 10732134400 1372970496 14.354 100 13 26 0 1385640560 9860288 2809776
01:06:33 38496 8192000000 10266175584 10722332672 1329241024 13.960 97 15 30 0 1342222080 9296112 3684944
01:08:15 52521 8192000000 10287483600 10746720256 1358806928 14.227 98 22 44 0 1376991904 13593024 4591952
01:10:17 93632 8192000000 10292110448 10762412032 1374033392 14.363 99 14 28 0 1388487696 11669824 2784480
01:12:52 21001 8192000000 10300952288 10750939136 1378539616 14.403 99 24 48 1 1391706560 7726928 5440016
01:14:13 24546 8192000000 10303934336 10752466944 1367714256 14.307 98 29 60 0 1384516112 8580752 8221104
01:15:46 99495 8192000000 10312261968 10770096128 1391159408 14.516 99 18 36 0 1413093552 17523232 4410912
01:18:26 84261 8192000000 10307729168 10760720384 1394272400 14.544 99 23 46 2 1411877392 12637936 4967056
01:21:10 37977 8192000000 10290741968 10747760640 1345349664 14.106 96 32 63 0 1364756640 12772576 6634400
01:22:48 60957 8192000000 10267222512 10742648832 1360950624 14.246 98 3 6 0 1371305648 9721040 633984
01:24:49 41189 8192000000 10285091808 10744565760 1367723792 14.307 99 14 28 0 1385075632 14254416 3097424
01:26:33 73488 8192000000 10260230880 10727895040 1328431632 13.953 97 15 30 0 1345403008 13086416 3884960
01:28:46 36379 8192000000 10284347440 10742394880 1380445696 14.421 100 22 44 0 1396700480 11322976 4931808
01:30:23 85505 8192000000 10232720640 10691899392 1243804144 13.181 91 11 22 0 1264859488 18321504 2733840
01:32:49 17297 8192000000 10251181536 10686119936 1320899584 13.885 99 53 106 2 1342094272 8940576 12254112
01:34:06 116585 8192000000 10264952800 10720030720 1308818576 13.775 97 12 24 1 1333362064 21880736 2662752
01:37:06 90997 8192000000 9458844208 9801003008 573546544 6.543 43 7 14 0 583499792 8520816 1432432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-12-2022 01:39:22 to 23-12-2022 01:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246194
MON$OLDEST_ACTIVE               247277
MON$OLDEST_SNAPSHOT             247277
MON$NEXT_TRANSACTION            247277
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-03 16:41:55.3470
MON$PAGES                       2041888
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1087: 
:1088: mscs-test-161.budzdorov.ru    Fri Dec 23 01:38:39 2022
:1089:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1090: 
:1091: 
:1092: mscs-test-161.budzdorov.ru    Fri Dec 23 01:38:40 2022
:1093:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1094: 
:1095: 
:1096: mscs-test-161.budzdorov.ru    Fri Dec 23 01:38:40 2022
:1097:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1098: 
:1099: 
:1100: mscs-test-161.budzdorov.ru    Fri Dec 23 01:38:40 2022
:1101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1102: 
:1103: 
:1104: mscs-test-161.budzdorov.ru    Fri Dec 23 01:38:40 2022
:1105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1106: 
--- end of diff output ---

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

23.12.22 01:39:42. There are 3 logs in /var/tmp/logs-oltp30 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-23 01:39:44 No severe PSQL-related problems occured
23.12.22 01:39:46. End of report.