Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.02.2023 22: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 15463516 kB
MemAvailable 30421780 kB
Buffers 0 kB
Cached 16234044 kB
SwapCached 3668 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 2023-02-08 22:38:38
DTS_END                         2023-02-08 22:38:38.6790
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/35980
TRN_ID                          233901
ATT_ID                          687
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: 9007.43 21189 1080892
customer order: creation 168.08 374 20169
customer order: refuse 14.63 356 1755
order to supplier: creation 44.47 1245 5336
order to supplier: removal 3.42 100333 410
invoice (draft): creation 45.39 3408 5447
invoice (draft): removal 52.23 750 6268
invoice accept: apply 40.08 17924 4809
invoice accept: cancel 46.53 804 5583
customer reserve: creation 3740.17 176 448820
customer reserve: removal 4564.27 59 547713
realization accept: apply 81.33 174 9759
realization accept: cancel 79.86 109 9583
payment from customer: creation 57.70 120 6924
payment from customer: removal 5.93 71 712
payment to supplier: creation 48.50 128 5820
payment to supplier: removal 3.36 90 403
service: total inventory turnovers 4.77 1147 572
service: total monetary turnovers 5.84 25 701
service: aggregate perf. data 0.87 15070 104
service: refresh index statistics 0.03 281410 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-02-2023 22:39:30 to 08-02-2023 22:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2065
TEST_TIME 2 3856
TEST_TIME 3 3046
TEST_TIME 4 3053
TEST_TIME 5 3520
TEST_TIME 6 4293
TEST_TIME 7 4771
TEST_TIME 8 5021
TEST_TIME 9 5059
TEST_TIME 10 5566
TEST_TIME 11 6079
TEST_TIME 12 6279
TEST_TIME 13 6599
TEST_TIME 14 6589
TEST_TIME 15 6760
TEST_TIME 16 7125
TEST_TIME 17 7084
TEST_TIME 18 7451
TEST_TIME 19 7926
TEST_TIME 20 8025
TEST_TIME 21 8346
TEST_TIME 22 8553
TEST_TIME 23 8711
TEST_TIME 24 8915
TEST_TIME 25 9216
TEST_TIME 26 9727
TEST_TIME 27 9759
TEST_TIME 28 9561
TEST_TIME 29 9780
TEST_TIME 30 9897
TEST_TIME 31 9854
TEST_TIME 32 10030
TEST_TIME 33 9956
TEST_TIME 34 9794
TEST_TIME 35 9909
TEST_TIME 36 9737
TEST_TIME 37 9634
TEST_TIME 38 9498
TEST_TIME 39 9352
TEST_TIME 40 9284
TEST_TIME 41 9184
TEST_TIME 42 9216
TEST_TIME 43 9282
TEST_TIME 44 9392
TEST_TIME 45 9222
TEST_TIME 46 9114
TEST_TIME 47 9180
TEST_TIME 48 9053
TEST_TIME 49 9049
TEST_TIME 50 8965
TEST_TIME 51 8902
TEST_TIME 52 8880
TEST_TIME 53 8806
TEST_TIME 54 8718
TEST_TIME 55 8748
TEST_TIME 56 8638
TEST_TIME 57 8638
TEST_TIME 58 8700
TEST_TIME 59 8710
TEST_TIME 60 8696
TEST_TIME 61 8668
TEST_TIME 62 8738
TEST_TIME 63 8724
TEST_TIME 64 8787
TEST_TIME 65 8754
TEST_TIME 66 8819
TEST_TIME 67 8884
TEST_TIME 68 8917
TEST_TIME 69 8964
TEST_TIME 70 8977
TEST_TIME 71 8993
TEST_TIME 72 8997
TEST_TIME 73 9050
TEST_TIME 74 9055
TEST_TIME 75 8978
TEST_TIME 76 8939
TEST_TIME 77 8983
TEST_TIME 78 8911
TEST_TIME 79 8912
TEST_TIME 80 8894
TEST_TIME 81 8847
TEST_TIME 82 8846
TEST_TIME 83 8822
TEST_TIME 84 8767
TEST_TIME 85 8802
TEST_TIME 86 8778
TEST_TIME 87 8822
TEST_TIME 88 8819
TEST_TIME 89 8855
TEST_TIME 90 8935
TEST_TIME 91 8921
TEST_TIME 92 8949
TEST_TIME 93 8958
TEST_TIME 94 8996
TEST_TIME 95 9021
TEST_TIME 96 9071
TEST_TIME 97 9099
TEST_TIME 98 9063
TEST_TIME 99 9114
TEST_TIME 100 9156
TEST_TIME 101 9128
TEST_TIME 102 9083
TEST_TIME 103 9036
TEST_TIME 104 9100
TEST_TIME 105 9056
TEST_TIME 106 9069
TEST_TIME 107 9043
TEST_TIME 108 9016
TEST_TIME 109 8988
TEST_TIME 110 8952
TEST_TIME 111 8911
TEST_TIME 112 8967
TEST_TIME 113 8928
TEST_TIME 114 8914
TEST_TIME 115 8911
TEST_TIME 116 9000
TEST_TIME 117 9000
TEST_TIME 118 9055
TEST_TIME 119 9044
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2023 22:39:33 to 08-02-2023 22:39:37.

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 1496089 1496089 0 0.00 0 22127 6 0 0
doc_list_biud 1496089 1496089 0 0.00 0 38118 27 0 0
sp_add_invoice_to_stock 4915 4809 106 2.15 44 173815 17925 0 106
sp_cancel_adding_invoice 5583 5583 0 0.00 5 54546 805 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 13780 356 0 0
sp_cancel_customer_reserve 547713 547713 0 0.00 0 38121 59 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 5711 71 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 7328 91 0 0
sp_cancel_supplier_invoice 6279 6268 11 0.17 1 22997 750 0 11
sp_cancel_supplier_order 410 410 0 0.00 8 887285 100333 0 0
sp_cancel_write_off 9583 9583 0 0.00 1 21428 110 0 0
sp_client_order 20169 20169 0 0.00 3 21768 375 0 0
sp_customer_reserve 448820 448820 0 0.00 0 35400 176 0 0
sp_fill_shopping_cart 30946 30946 0 0.00 1 11663 38 0 0
sp_fill_shopping_cart_clo_res 448819 448819 0 0.00 0 32009 16 0 0
sp_get_clo_for_invoice 4807 4807 0 0.00 0 40294 353 0 0
sp_kill_cost_storno 16281 16281 0 0.00 0 9819 55 0 0
sp_kill_qstorno_ret_qs2qd 1111687 1111687 0 0.00 0 38113 32 0 0
sp_kill_qty_storno 585878 585878 0 0.00 0 38118 69 0 0
sp_lock_selected_doc 20266 20266 0 0.00 0 7696 4 0 0
sp_make_cost_storno 27310 27310 0 0.00 0 19341 51 0 0
sp_multiply_rows_for_pdistr 27310 27310 0 0.00 0 18833 25 0 0
sp_multiply_rows_for_qdistr 20169 20169 0 0.00 0 21759 152 0 0
sp_pay_from_customer 6924 6924 0 0.00 4 19372 121 0 0
sp_pay_to_supplier 5820 5820 0 0.00 4 11108 129 0 0
sp_payment_common 12744 12744 0 0.00 2 8836 29 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 13772 331 0 0
sp_qd_handle_on_invoice_upd_sts 10390 10390 0 0.00 0 31359 333 0 0
sp_qd_handle_on_reserve_upd_sts 9759 9759 0 0.00 0 20156 63 0 0
sp_reserve_write_off 9759 9759 0 0.00 6 20219 174 0 0
sp_supplier_invoice 5447 5447 0 0.00 6 90396 3408 0 0
sp_supplier_order 5336 5336 0 0.00 4 36007 1245 0 0
srv_aggregate_perf_data 104 104 0 0.00 2104 132981 15070 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1798 110280 47901 0 0
srv_find_qd_qs_mism 1034057 1034057 0 0.00 0 22125 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 31 101220 1147 0 0
srv_make_money_saldo 701 701 0 0.00 1 2050 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 150227 545280 281410 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 4809 4809 0 0.00 0 15117 199 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 5429 97 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 3495 14 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 761 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 3369 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1130 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 2020 33 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 19800 1688 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 8648 23 0 0
t$perf-norm:sp_client_order 20169 20169 0 0.00 0 10194 20 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 7038 22 0 0
t$perf-norm:sp_pay_from_customer 6924 6924 0 0.00 0 9042 15 0 0
t$perf-norm:sp_pay_to_supplier 5820 5820 0 0.00 0 8907 12 0 0
t$perf-norm:sp_reserve_write_off 9759 9759 0 0.00 0 10236 14 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 8849 24 0 0
t$perf-norm:sp_supplier_order 5336 5336 0 0.00 0 5823 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 663 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3591 61 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 1213 21 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 1459 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9824 9612 212 2.15 0 14623 27 0 212
v_all_customers 36744 36744 0 0.00 0 18 0 0 0
v_all_suppliers 30066 30066 0 0.00 0 24 1 0 0
v_all_wares 40338 40338 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 781 6 0 0
v_cancel_client_order 3510 3510 0 0.00 0 4653 5 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 5701 12 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 12518 13 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 7299 14 0 0
v_cancel_supplier_invoice 794 772 22 2.77 0 680 14 0 22
v_cancel_supplier_order 820 820 0 0.00 0 830 4 0 0
v_cancel_write_off 1924 1924 0 0.00 0 5054 15 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 421 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 5691 20 0 0
v_max_id_ord_sup 5441 5441 0 0.00 0 3573 5 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 3614 5 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 2343 3 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 8582 10 0 0
v_min_id_clo_res 980 980 0 0.00 0 4150 9 0 0
v_min_id_ord_sup 5441 5441 0 0.00 0 5056 9 0 0
v_min_non_paid_invoice 5820 5820 0 0.00 0 8831 24 0 0
v_min_non_paid_realizn 6924 6924 0 0.00 0 8464 17 0 0
v_our_firm 9918 9918 0 0.00 0 124 1 0 0
v_reserve_write_off 19518 19518 0 0.00 0 6998 9 0 0
x_lock_depdocs_on_canc_invoice 11465 11465 0 0.00 0 22637 116 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 20896 340 0 0
x_make_qstorno_1000_1200 5336 5336 0 0.00 1 35954 1174 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 2 89899 3268 0 0
x_make_qstorno_2100_3300 448820 448820 0 0.00 0 35399 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2023 22:39:38 to 08-02-2023 22:39:42.

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
20:08:42 11 8192000000 8825362528 8846090240 26830528 0.326 11 6 4 0 27033168 93504 109136
20:09:43 25003 8192000000 9888610352 10195288064 970508800 10.592 100 89 178 0 1000498608 5627616 24362192
20:11:16 10849 8192000000 9930284464 10244689920 1010513456 10.980 100 94 187 0 1042412576 7793648 24105472
20:12:34 2912 8192000000 9955355280 10278174720 1036972864 11.236 100 90 180 0 1069751296 9776176 23002256
20:13:47 8999 8192000000 9981931088 10325786624 1078141168 11.630 100 70 138 1 1104305952 9478976 16685808
20:14:58 7365 8192000000 10062416720 10414366720 1148428736 12.295 100 82 165 0 1178860208 11329616 19101856
20:16:08 2806 8192000000 10112166720 10476511232 1199715872 12.774 100 83 166 0 1231687552 12847392 19124288
20:17:13 2923 8192000000 10146628176 10517688320 1231308176 13.066 100 85 165 2 1262640608 13542128 17790304
20:18:18 2232 8192000000 10164533232 10545274880 1255018912 13.284 100 77 154 0 1285667856 13393488 17255456
20:19:22 1061 8192000000 10191233536 10577137664 1286674848 13.574 100 78 156 0 1315934000 11804560 17454592
20:20:24 24940 8192000000 10205091008 10599636992 1298245376 13.679 100 61 123 2 1321475344 9506720 13723248
20:21:50 7099 8192000000 10195885616 10605998080 1307036528 13.759 100 34 67 0 1321912800 7287664 7588608
20:22:57 7597 8192000000 10207059504 10622021632 1320314064 13.880 100 22 44 0 1331701424 6711808 4675552
20:24:12 13653 8192000000 10210968160 10630258688 1330324448 13.970 100 17 35 0 1339075376 5287088 3463840
20:25:29 28887 8192000000 10232794624 10643447808 1331555552 13.981 100 48 96 1 1348928240 6394608 10978080
20:26:59 4229 8192000000 10234018384 10649985024 1326076976 13.932 99 35 67 1 1341112368 6823392 8212000
20:28:07 10535 8192000000 10221469312 10645737472 1326271600 13.933 99 22 43 0 1336695504 5678976 4744928
20:29:22 11396 8192000000 10200290112 10616176640 1299543424 13.691 99 20 41 1 1309958736 5252864 5162448
20:30:36 25658 8192000000 10151767712 10561138688 1231860320 13.071 97 22 44 0 1242262192 5242416 5159456
20:32:02 4845 8192000000 10125078688 10520711168 1188124976 12.666 95 29 59 0 1199905024 5220224 6559824
20:33:07 7336 8192000000 10146615312 10547924992 1235906304 13.109 98 19 38 0 1249897968 9734032 4257632
20:34:17 9981 8192000000 10173719568 10574168064 1261415392 13.343 99 26 52 0 1281549120 14004528 6129200
20:35:33 28741 8192000000 10183070960 10595831808 1289072784 13.596 100 41 82 0 1308958480 10766160 9119536
20:37:04 8423 8192000000 10202044560 10609074176 1305125424 13.742 100 35 71 0 1319429664 6777248 7526992
20:38:14 12943 8192000000 10210861760 10625748992 1321487424 13.890 100 13 26 0 1329273344 5341808 2444112
20:39:34 22964 8192000000 10245129552 10656530432 1339005056 14.048 100 52 102 0 1358219200 7770752 11443392
20:41:00 110279 8192000000 10270898192 10692808704 1362988208 14.264 100 34 68 1 1382908512 12321296 7599008
20:43:57 18794 8192000000 10284804704 10705895424 1371106992 14.337 100 32 62 0 1397133296 18647104 7379200
20:45:22 13048 8192000000 10305692320 10722914304 1382790736 14.441 100 49 98 0 1411503984 17337520 11375728
20:46:44 11095 8192000000 10306072448 10729324544 1390381584 14.509 100 55 105 0 1416553728 14285312 11886832
20:47:59 1797 8192000000 10329135136 10749042688 1403392384 14.625 100 61 120 2 1436371856 18661632 14317840
20:49:05 3340 8192000000 10332489664 10743209984 1393956096 14.541 99 73 146 0 1434051856 22941840 17153920
20:50:15 5773 8192000000 10345820960 10767564800 1417538384 14.751 100 74 144 1 1457417088 23043392 16835312
20:51:26 3111 8192000000 10346665184 10760945664 1407041072 14.658 100 72 143 0 1448127840 24342528 16744240
20:52:37 3085 8192000000 10360449760 10773291008 1411784512 14.700 100 80 158 0 1464243952 32758080 19701360
20:53:45 5866 8192000000 10351644448 10768609280 1393367696 14.536 99 70 140 1 1445256384 34239296 17649392
20:54:58 6822 8192000000 10326290768 10743787520 1385550480 14.466 100 63 125 0 1435374768 33877728 15946560
20:56:09 3348 8192000000 10332354016 10749190144 1380812032 14.424 100 57 113 0 1438224880 41963504 15449344
20:57:23 5243 8192000000 10325506400 10744594432 1378377536 14.402 100 59 119 2 1434289456 40473088 15438832
20:58:32 5170 8192000000 10306710944 10725838848 1363724512 14.271 100 45 89 1 1413771488 38208672 11838304
20:59:43 9541 8192000000 10280341648 10702528512 1342880144 14.083 100 38 76 0 1388893008 36020032 9992832
21:00:58 6481 8192000000 10308222512 10722742272 1357726384 14.217 100 55 109 2 1405972672 33708944 14537344
21:02:07 24438 8192000000 10294649056 10721951744 1342012048 14.076 99 25 50 0 1388610720 39423776 7174896
21:03:49 23668 8192000000 10274704112 10702598144 1329590000 13.963 99 28 54 0 1383061040 45387776 8083264
21:05:18 37340 8192000000 10293375120 10730840064 1368157616 14.311 100 25 50 1 1401460944 26861152 6442176
21:07:24 18338 8192000000 10310998480 10746712064 1358924896 14.228 99 46 90 0 1406572896 36763440 10884560
21:09:47 22738 8192000000 10282384688 10711650304 1344586608 14.099 99 23 43 1 1373823888 23839040 5398240
21:11:13 50452 8192000000 10264364864 10707546112 1343234064 14.087 98 12 24 0 1358502256 12398608 2869584
21:13:05 64653 8192000000 10247555264 10702123008 1328066832 13.950 98 15 30 0 1343625312 12379280 3179200
21:15:13 28575 8192000000 10270924736 10713276416 1362827168 14.263 100 25 50 0 1380206272 12157424 5221680
21:16:49 44278 8192000000 10293546704 10728304640 1372047472 14.345 100 28 57 0 1392561824 13093488 7420864
21:18:36 76246 8192000000 10287932224 10731864064 1367135328 14.301 99 22 42 1 1393350816 20737872 5477616
21:21:28 72606 8192000000 10281363296 10743050240 1375203744 14.374 100 10 20 1 1405478080 27335888 2938448
21:23:41 92342 8192000000 10280463824 10730631168 1372615280 14.350 99 40 80 0 1393500032 11963808 8920944
21:26:15 36568 8192000000 10280399456 10738413568 1382091024 14.435 100 11 22 0 1398115616 13520992 2503600
21:27:52 43218 8192000000 10298069744 10736984064 1373911120 14.362 99 21 40 1 1385885008 6974496 4999392
21:29:36 66937 8192000000 10282759824 10738978816 1378274368 14.401 99 14 29 0 1389534816 8469728 2790720
21:31:43 57945 8192000000 10304795408 10760781824 1392806752 14.531 99 16 32 0 1405140320 8702208 3631360
21:33:45 68314 8192000000 10309690000 10763104256 1403361152 14.625 100 19 38 0 1419401600 11436624 4603824
21:36:03 49442 8192000000 10298435024 10766864384 1401156016 14.605 100 15 30 0 1416669536 12062848 3450672
21:37:54 84747 8192000000 10312063616 10776182784 1394110128 14.543 99 7 14 0 1402756432 7477728 1168576
21:40:39 92917 8192000000 10323559968 10785353728 1392634016 14.529 98 19 38 0 1408265360 11734816 3896528
21:43:14 30314 8192000000 10315729152 10764939264 1406006368 14.648 100 33 66 1 1422419584 8925264 7487952
21:44:44 25332 8192000000 10304638752 10740449280 1380233392 14.419 99 33 66 1 1395300848 8056880 7010576
21:46:16 78012 8192000000 10287174640 10737225728 1332614624 13.991 95 27 54 0 1351751552 13131296 6005632
21:48:40 98949 8192000000 10260929296 10699309056 1207854896 12.849 87 16 32 0 1227822448 16496432 3471120
21:51:22 109795 8192000000 10257189632 10708320256 1285252400 13.561 95 21 42 0 1306593328 16883680 4457248
21:55:03 103708 8192000000 10231364400 10679496704 1227848112 13.034 91 11 22 0 1245218736 14645296 2725328
21:58:08 74222 8192000000 10242664368 10693636096 1318661328 13.865 98 13 26 0 1335530704 13848528 3020848
22:00:24 69182 8192000000 10228787216 10668318720 1272236912 13.442 95 18 36 0 1285027104 8018896 4771296
22:02:34 68765 8192000000 10230557120 10675994624 1308497456 13.772 98 19 38 0 1324437392 11411744 4528192
22:04:45 75802 8192000000 10249782000 10697211904 1316731552 13.847 98 13 24 0 1332089328 12640928 2716848
22:07:01 61182 8192000000 10257602528 10700603392 1353810304 14.182 100 29 58 0 1372832480 12568384 6453792
22:09:19 73544 8192000000 10289211728 10738356224 1359322144 14.231 99 17 34 0 1373798272 10585840 3890288
22:11:35 95396 8192000000 10287457120 10747289600 1341507744 14.071 97 9 18 0 1354441312 11402592 1530976
22:14:16 57189 8192000000 10309075744 10761080832 1391426496 14.519 100 23 46 0 1411458128 14896576 5135056
22:16:20 22121 8192000000 10315491936 10756108288 1396639232 14.565 100 31 62 0 1414734128 11525312 6569584
22:17:47 97391 8192000000 10300652288 10758918144 1358579632 14.225 97 15 30 0 1373111040 11232720 3298688
22:20:25 37004 8192000000 10319275200 10762911744 1390403152 14.509 99 50 97 0 1416724896 14882400 11439344
22:23:00 69219 8192000000 10305511904 10766786560 1395296176 14.553 100 18 34 0 1412417904 12969200 4152528
22:25:11 58284 8192000000 10285225088 10746896384 1392090944 14.525 100 9 18 0 1401128208 7175888 1861376
22:27:09 72176 8192000000 10280741808 10734694400 1336464864 14.026 95 14 28 0 1350760832 11025904 3270064
22:29:31 68416 8192000000 10285509104 10754215936 1375677152 14.378 99 14 28 0 1393958016 14634320 3646544
22:31:40 45995 8192000000 10288489936 10740817920 1356274016 14.204 97 26 52 0 1376409024 14062720 6072288
22:33:26 44346 8192000000 10290381792 10747047936 1359285136 14.231 98 16 32 0 1379063264 15686768 4091360
22:35:11 100919 8192000000 10266979440 10723139584 1306135472 13.751 95 11 22 0 1322524208 13826848 2561888
22:38:00 41010 8192000000 9453254784 9771274240 544034912 6.227 41 13 26 0 553484704 6745584 2704208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-02-2023 22:39:44 to 08-02-2023 22:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 08-02-2023 22:40:00 to 08-02-2023 22:40:02.

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          237558
MON$OLDEST_ACTIVE               237559
MON$OLDEST_SNAPSHOT             237559
MON$NEXT_TRANSACTION            237559
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                       2015552
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.33661 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33661 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33661 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33661 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Feb  8 22:38:47 2023
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
--- end of diff output ---

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

08.02.23 22:40:03. 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
2023-02-08 22:40:06 No severe PSQL-related problems occured
08.02.23 22:40:07. End of report.