Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2022 16: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 15284724 kB
MemAvailable 30125024 kB
Buffers 0 kB
Cached 16448916 kB
SwapCached 3544 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-28 16:38:38
DTS_END                         2022-12-28 16:38:38.7280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37234
TRN_ID                          238235
ATT_ID                          711
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: 9155.14 25373 1098617
customer order: creation 165.99 379 19919
customer order: refuse 13.69 325 1643
order to supplier: creation 44.33 1173 5320
order to supplier: removal 3.38 90781 406
invoice (draft): creation 45.11 3098 5413
invoice (draft): removal 52.58 690 6309
invoice accept: apply 40.33 16020 4839
invoice accept: cancel 47.93 780 5752
customer reserve: creation 3743.13 157 449175
customer reserve: removal 4714.98 51 565798
realization accept: apply 79.93 188 9592
realization accept: cancel 77.29 104 9275
payment from customer: creation 57.44 147 6893
payment from customer: removal 5.61 95 673
payment to supplier: creation 48.53 128 5823
payment to supplier: removal 3.46 118 415
service: total inventory turnovers 4.36 1280 523
service: total monetary turnovers 6.08 32 729
service: aggregate perf. data 0.97 16282 116
service: refresh index statistics 0.03 375625 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2022 16:40:02 to 28-12-2022 16:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2336
TEST_TIME 2 3160
TEST_TIME 3 5183
TEST_TIME 4 4578
TEST_TIME 5 4263
TEST_TIME 6 4184
TEST_TIME 7 4052
TEST_TIME 8 4119
TEST_TIME 9 4590
TEST_TIME 10 5065
TEST_TIME 11 5555
TEST_TIME 12 6364
TEST_TIME 13 6965
TEST_TIME 14 7570
TEST_TIME 15 8096
TEST_TIME 16 8404
TEST_TIME 17 8618
TEST_TIME 18 8500
TEST_TIME 19 8852
TEST_TIME 20 8947
TEST_TIME 21 9225
TEST_TIME 22 9136
TEST_TIME 23 9655
TEST_TIME 24 9535
TEST_TIME 25 9929
TEST_TIME 26 9795
TEST_TIME 27 10125
TEST_TIME 28 10289
TEST_TIME 29 10307
TEST_TIME 30 10258
TEST_TIME 31 10042
TEST_TIME 32 10054
TEST_TIME 33 9888
TEST_TIME 34 9884
TEST_TIME 35 9965
TEST_TIME 36 9813
TEST_TIME 37 9743
TEST_TIME 38 9673
TEST_TIME 39 9608
TEST_TIME 40 9494
TEST_TIME 41 9467
TEST_TIME 42 9431
TEST_TIME 43 9247
TEST_TIME 44 9461
TEST_TIME 45 9558
TEST_TIME 46 9542
TEST_TIME 47 9510
TEST_TIME 48 9574
TEST_TIME 49 9623
TEST_TIME 50 9578
TEST_TIME 51 9466
TEST_TIME 52 9482
TEST_TIME 53 9558
TEST_TIME 54 9662
TEST_TIME 55 9699
TEST_TIME 56 9661
TEST_TIME 57 9683
TEST_TIME 58 9795
TEST_TIME 59 9690
TEST_TIME 60 9620
TEST_TIME 61 9522
TEST_TIME 62 9539
TEST_TIME 63 9550
TEST_TIME 64 9456
TEST_TIME 65 9451
TEST_TIME 66 9409
TEST_TIME 67 9406
TEST_TIME 68 9381
TEST_TIME 69 9367
TEST_TIME 70 9400
TEST_TIME 71 9463
TEST_TIME 72 9457
TEST_TIME 73 9481
TEST_TIME 74 9545
TEST_TIME 75 9509
TEST_TIME 76 9498
TEST_TIME 77 9519
TEST_TIME 78 9528
TEST_TIME 79 9622
TEST_TIME 80 9666
TEST_TIME 81 9577
TEST_TIME 82 9492
TEST_TIME 83 9501
TEST_TIME 84 9413
TEST_TIME 85 9431
TEST_TIME 86 9364
TEST_TIME 87 9350
TEST_TIME 88 9317
TEST_TIME 89 9293
TEST_TIME 90 9250
TEST_TIME 91 9199
TEST_TIME 92 9238
TEST_TIME 93 9263
TEST_TIME 94 9271
TEST_TIME 95 9231
TEST_TIME 96 9270
TEST_TIME 97 9303
TEST_TIME 98 9299
TEST_TIME 99 9289
TEST_TIME 100 9283
TEST_TIME 101 9387
TEST_TIME 102 9403
TEST_TIME 103 9381
TEST_TIME 104 9409
TEST_TIME 105 9415
TEST_TIME 106 9427
TEST_TIME 107 9416
TEST_TIME 108 9444
TEST_TIME 109 9401
TEST_TIME 110 9393
TEST_TIME 111 9352
TEST_TIME 112 9321
TEST_TIME 113 9280
TEST_TIME 114 9252
TEST_TIME 115 9251
TEST_TIME 116 9271
TEST_TIME 117 9219
TEST_TIME 118 9219
TEST_TIME 119 9196
TEST_TIME 120 9157

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2022 16:40:05 to 28-12-2022 16:40:09.

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 1515634 1515634 0 0.00 0 29206 5 0 0
doc_list_biud 1515634 1515634 0 0.00 0 23552 25 0 0
sp_add_invoice_to_stock 4908 4839 69 1.40 28 152205 16020 0 69
sp_cancel_adding_invoice 5752 5752 0 0.00 4 95283 781 0 0
sp_cancel_client_order 1643 1643 0 0.00 4 21661 325 0 0
sp_cancel_customer_reserve 565798 565798 0 0.00 0 22906 52 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 6541 95 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 2 8647 119 0 0
sp_cancel_supplier_invoice 6315 6309 6 0.09 1 15240 690 0 6
sp_cancel_supplier_order 406 406 0 0.00 7 802002 90781 0 0
sp_cancel_write_off 9275 9275 0 0.00 1 16378 105 0 0
sp_client_order 19919 19919 0 0.00 3 28509 379 0 0
sp_customer_reserve 449175 449175 0 0.00 0 47430 158 0 0
sp_fill_shopping_cart 30644 30644 0 0.00 1 28997 39 0 0
sp_fill_shopping_cart_clo_res 449171 449171 0 0.00 0 15020 15 0 0
sp_get_clo_for_invoice 4835 4835 0 0.00 0 37706 359 0 0
sp_kill_cost_storno 16115 16115 0 0.00 0 16374 52 0 0
sp_kill_qstorno_ret_qs2qd 1147586 1147586 0 0.00 0 22870 28 0 0
sp_kill_qty_storno 603610 603610 0 0.00 0 23546 61 0 0
sp_lock_selected_doc 19999 19999 0 0.00 0 7290 4 0 0
sp_make_cost_storno 27143 27143 0 0.00 0 27996 56 0 0
sp_multiply_rows_for_pdistr 27143 27143 0 0.00 0 27714 30 0 0
sp_multiply_rows_for_qdistr 19919 19919 0 0.00 0 26131 179 0 0
sp_pay_from_customer 6893 6893 0 0.00 4 28842 148 0 0
sp_pay_to_supplier 5823 5823 0 0.00 4 26674 129 0 0
sp_payment_common 12716 12716 0 0.00 1 26641 37 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 21655 300 0 0
sp_qd_handle_on_invoice_upd_sts 10587 10587 0 0.00 0 23506 322 0 0
sp_qd_handle_on_reserve_upd_sts 9592 9592 0 0.00 0 12193 65 0 0
sp_reserve_write_off 9592 9592 0 0.00 6 28650 189 0 0
sp_supplier_invoice 5413 5413 0 0.00 6 90252 3099 0 0
sp_supplier_order 5320 5320 0 0.00 5 28782 1174 0 0
srv_aggregate_perf_data 116 116 0 0.00 2595 128525 16283 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1278 119052 49156 0 0
srv_find_qd_qs_mism 1052726 1052726 0 0.00 0 28497 4 0 0
srv_make_invnt_saldo 523 523 0 0.00 56 41908 1281 0 0
srv_make_money_saldo 729 729 0 0.00 1 4797 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 157167 546906 375625 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4840 4840 0 0.00 0 27899 171 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 13201 110 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 13734 19 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 12175 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2736 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 6231 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 619 10 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 34113 1437 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 9506 26 0 0
t$perf-norm:sp_client_order 19919 19919 0 0.00 0 12350 17 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 12376 19 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 9552 12 0 0
t$perf-norm:sp_pay_to_supplier 5823 5823 0 0.00 0 8934 11 0 0
t$perf-norm:sp_reserve_write_off 9592 9592 0 0.00 0 8943 11 0 0
t$perf-norm:sp_supplier_invoice 5413 5413 0 0.00 0 7834 18 0 0
t$perf-norm:sp_supplier_order 5320 5320 0 0.00 0 7641 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 942 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1353 23 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 720 17 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 5561 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9806 9668 138 1.40 0 4841 16 0 138
v_all_customers 36242 36242 0 0.00 0 18 0 0 0
v_all_suppliers 30022 30022 0 0.00 0 44 1 0 0
v_all_wares 39838 39838 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 3041 10 0 0
v_cancel_client_order 3286 3286 0 0.00 0 4429 4 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 5494 13 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 1874 8 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 8628 20 0 0
v_cancel_supplier_invoice 824 812 12 1.45 0 1038 14 0 12
v_cancel_supplier_order 812 812 0 0.00 0 1953 7 0 0
v_cancel_write_off 1950 1950 0 0.00 0 2304 14 0 0
v_max_id_clo_ord 5318 5318 0 0.00 0 1765 1 0 0
v_max_id_clo_res 925 925 0 0.00 0 2855 16 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 4698 5 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 2975 7 0 0
v_max_non_paid_realizn 3714 3714 0 0.00 0 2000 4 0 0
v_min_id_clo_ord 5318 5318 0 0.00 0 12357 15 0 0
v_min_id_clo_res 925 925 0 0.00 0 5205 8 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 6179 7 0 0
v_min_non_paid_invoice 5823 5823 0 0.00 0 26633 22 0 0
v_min_non_paid_realizn 6893 6893 0 0.00 0 26627 37 0 0
v_our_firm 9954 9954 0 0.00 0 24 1 0 0
v_reserve_write_off 19182 19182 0 0.00 0 5949 7 0 0
x_lock_depdocs_on_canc_invoice 11655 11655 0 0.00 0 13414 114 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 11128 379 0 0
x_make_qstorno_1000_1200 5320 5320 0 0.00 1 28760 1098 0 0
x_make_qstorno_1200_2000 5413 5413 0 0.00 3 89107 2959 0 0
x_make_qstorno_2100_3300 449175 449175 0 0.00 0 47430 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 16:40:10 to 28-12-2022 16:40:14.

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
14:08:42 10 8192000000 8804108896 8817377280 9181280 0.111 4 1 2 0 9283568 59504 42784
14:09:43 28188 8192000000 9908388928 10220969984 988987456 10.772 100 92 184 0 1019543776 6119456 24436864
14:11:22 23497 8192000000 9963311584 10287742976 1039888400 11.264 100 87 174 0 1071297088 8102224 23306464
14:12:49 15035 8192000000 9993199216 10330390528 1080862000 11.656 100 88 178 0 1112853200 9763360 22227840
14:14:13 3110 8192000000 10038065632 10387435520 1128397168 12.106 100 81 161 0 1159184144 11031680 19755296
14:15:21 8475 8192000000 10086067024 10448801792 1177792016 12.570 100 82 163 0 1208872576 12952048 18128512
14:16:32 3924 8192000000 10140920224 10510524416 1226223696 13.019 100 81 162 0 1256995600 13010352 17761552
14:17:39 1991 8192000000 10175247584 10548563968 1256355216 13.297 100 88 175 1 1288385072 12792928 19236928
14:18:42 1865 8192000000 10190671392 10572582912 1280189248 13.515 100 85 166 0 1311433760 12637680 18606832
14:19:46 2033 8192000000 10215724720 10597388288 1293266384 13.634 100 91 180 1 1326971264 14374448 19330432
14:20:48 5115 8192000000 10199916640 10599448576 1300508992 13.700 100 59 120 0 1322225536 7921024 13795520
14:21:55 6371 8192000000 10191391056 10606714880 1307374368 13.762 100 13 26 1 1316006368 5967920 2664080
14:23:04 5348 8192000000 10201736960 10618802176 1318658160 13.865 100 22 44 0 1329850064 5934704 5257200
14:24:10 7229 8192000000 10214893024 10626297856 1323778784 13.911 100 30 57 1 1336156736 5673568 6704384
14:25:21 7466 8192000000 10231171344 10642739200 1331329568 13.979 100 26 52 2 1342724464 5999440 5395456
14:26:29 21498 8192000000 10230432896 10650779648 1335061584 14.013 100 38 76 1 1350385344 7263296 8060464
14:27:54 5413 8192000000 10242227888 10660954112 1345706592 14.109 100 23 46 1 1356372640 5975680 4690368
14:29:04 10130 8192000000 10205755424 10620239872 1314166400 13.824 100 31 61 0 1327767648 6994160 6607088
14:30:15 47789 8192000000 10160655728 10562609152 1202885856 12.803 93 19 38 0 1212636064 5401952 4348256
14:32:13 4619 8192000000 10132754000 10528231424 1235026976 13.100 98 31 62 1 1247748784 6135968 6585840
14:33:19 5368 8192000000 10120589328 10509836288 1203330208 12.807 96 26 54 0 1214265184 4931056 6003920
14:34:24 9126 8192000000 10173037440 10578345984 1267368976 13.398 99 24 47 1 1278330576 5603024 5358576
14:35:34 33050 8192000000 10187472512 10596462592 1288095344 13.587 100 44 88 0 1304328656 5170592 11062720
14:37:08 8145 8192000000 10189572688 10606092288 1300821792 13.703 100 26 51 0 1311772768 4945760 6005216
14:38:17 8682 8192000000 10212655424 10627534848 1319372320 13.871 100 14 28 0 1326597872 4371280 2854272
14:39:31 22849 8192000000 10249959376 10659581952 1338181184 14.041 100 48 96 0 1358011504 9299520 10530800
14:41:02 54300 8192000000 10254121760 10691039232 1359096768 14.229 100 25 50 0 1379304416 14287744 5919904
14:43:04 40811 8192000000 10286487696 10711535616 1378336224 14.402 100 34 66 0 1401255936 15084128 7835584
14:44:58 48002 8192000000 10309700224 10734186496 1391631152 14.520 100 44 90 0 1420813392 18895792 10286448
14:47:10 17338 8192000000 10322875920 10746773504 1378296496 14.401 98 51 101 0 1419386224 28293632 12796096
14:48:34 2008 8192000000 10359842032 10776989696 1417789840 14.753 100 80 159 0 1465203296 27538176 19875280
14:49:41 1863 8192000000 10339419968 10756415488 1391419696 14.519 98 64 127 1 1436508800 29244944 15844160
14:50:46 3539 8192000000 10344805632 10763251712 1403805456 14.629 100 73 145 0 1450801840 28196816 18799568
14:51:53 1277 8192000000 10343172080 10755899392 1390272768 14.508 100 89 176 2 1447822752 35528000 22021984
14:52:58 2112 8192000000 10319674464 10729762816 1375213056 14.374 100 69 130 1 1422086704 30794752 16078896
14:54:02 4526 8192000000 10314117264 10735976448 1366002048 14.291 98 58 115 0 1408826256 28789216 14034992
14:55:10 6210 8192000000 10292056864 10717523968 1362237968 14.257 99 38 75 0 1400297072 28510096 9549008
14:56:21 11265 8192000000 10310217488 10736508928 1375729216 14.378 100 32 64 0 1411731216 27924976 8077024
14:57:33 12951 8192000000 10289761216 10720464896 1339986048 14.057 98 28 56 2 1383600928 36065360 7549520
14:59:05 26846 8192000000 10298272640 10742984704 1365504256 14.287 99 22 44 1 1405603040 33410272 6688512
15:01:06 6132 8192000000 10315679536 10743681024 1372814608 14.352 100 41 82 1 1422530896 39312384 10403904
15:02:14 17766 8192000000 10283545248 10713640960 1330151520 13.969 98 20 40 0 1370411376 34465328 5794528
15:03:40 57776 8192000000 10292764704 10710069248 1322073936 13.895 98 38 75 0 1363627936 31457472 10096528
15:05:46 43857 8192000000 10250695168 10704322560 1325955376 13.931 99 16 31 0 1355322848 25542560 3824912
15:07:32 33772 8192000000 10238951856 10672238592 1321747632 13.893 98 38 75 0 1343664416 12589600 9327184
15:09:12 119051 8192000000 10251631920 10688339968 1307140352 13.760 97 20 40 0 1324774576 12602528 5031696
15:12:13 80446 8192000000 10244258704 10682859520 1287068896 13.578 95 12 24 1 1301997456 12416560 2512000
15:14:34 28843 8192000000 10268798816 10697760768 1359419616 14.232 100 33 66 1 1374138544 7097936 7620992
15:16:03 42777 8192000000 10288229168 10724847616 1375298240 14.374 100 20 40 1 1392417392 12526656 4592496
15:17:47 54813 8192000000 10299406720 10735570944 1370558032 14.332 99 34 68 0 1398646960 19990784 8098144
15:20:01 69588 8192000000 10296576464 10747187200 1386001712 14.470 100 13 26 0 1413854448 24630048 3222688
15:22:11 49059 8192000000 10295306208 10756780032 1387172880 14.481 100 10 20 0 1410862464 21264784 2424800
15:24:08 36625 8192000000 10315850816 10758451200 1395293456 14.553 100 27 50 0 1411115984 10052112 5770416
15:25:49 24664 8192000000 10311046560 10759028736 1404626256 14.636 100 35 70 0 1422477216 9209808 8641152
15:28:01 78688 8192000000 10320817264 10759610368 1379501184 14.412 98 8 16 0 1389387936 8130208 1756544
15:30:22 60914 8192000000 10326135344 10784866304 1400013600 14.595 99 20 40 0 1418578880 14216960 4348320
15:32:41 94573 8192000000 10309190480 10759655424 1351122720 14.158 96 14 28 0 1374295344 20232640 2939984
15:35:18 79380 8192000000 10335925360 10803781632 1415654544 14.734 100 12 24 0 1449873536 31406624 2812368
15:37:41 109441 8192000000 10286461184 10743992320 1339864656 14.056 95 12 25 0 1356888912 14434224 2590032
15:40:44 40785 8192000000 10290629648 10751946752 1376717536 14.387 99 19 38 1 1390808368 10221104 3869728
15:42:34 42744 8192000000 10279994048 10729426944 1366855760 14.299 99 10 20 0 1378507824 9596448 2055616
15:44:17 66075 8192000000 10275302720 10725154816 1346120000 14.113 98 13 26 0 1356066608 7041600 2905008
15:46:23 32364 8192000000 10299017392 10748985344 1388458064 14.492 100 28 56 0 1409007088 13956256 6592768
15:47:56 60968 8192000000 10282363712 10727804928 1343465200 14.089 97 38 76 0 1367214608 15383216 8366192
15:49:58 82954 8192000000 10260704064 10710573056 1292035936 13.623 94 19 38 0 1311849760 15418144 4395680
15:52:21 104229 8192000000 10264938000 10705244160 1263163168 13.359 92 19 38 0 1276201424 8587552 4450704
15:55:06 58115 8192000000 10279835984 10716094464 1323078320 13.905 97 21 41 0 1336322624 8835872 4408432
15:57:05 69516 8192000000 10256396736 10707861504 1286187616 13.569 95 8 16 0 1302511296 14772768 1550912
15:59:59 92937 8192000000 10247246016 10704134144 1346197104 14.113 100 22 44 1 1361338624 10248560 4892960
16:02:52 32077 8192000000 10272311248 10715836416 1354141664 14.185 99 37 75 1 1371890432 8907840 8840928
16:04:40 55403 8192000000 10239049296 10691182592 1314731200 13.829 97 9 18 0 1324132416 7257008 2144208
16:06:36 91027 8192000000 10259171968 10708865024 1319919968 13.876 97 15 29 0 1331218896 7781344 3517584
16:09:07 79244 8192000000 10274846832 10735869952 1372656400 14.351 100 16 32 0 1393329824 16562992 4110432
16:11:30 84753 8192000000 10270178640 10732544000 1336953520 14.030 97 7 14 0 1350145920 11855152 1337248
16:13:55 60515 8192000000 10292696192 10753536000 1394878304 14.549 100 18 36 0 1414550912 15335520 4337088
16:15:56 64032 8192000000 10305437760 10756882432 1366207424 14.293 98 9 18 0 1375588416 7525168 1855824
16:18:02 37613 8192000000 10314864240 10763833344 1392680080 14.530 100 29 58 0 1410227872 10625696 6922096
16:19:40 85588 8192000000 10306156432 10757517312 1400347664 14.598 100 16 33 2 1415182896 10993360 3841872
16:22:07 23415 8192000000 10321288304 10770505728 1392425328 14.528 99 38 76 0 1412521776 11708336 8388112
16:23:32 35118 8192000000 10329271088 10775834624 1405156672 14.641 100 27 54 0 1423357232 12400256 5800304
16:25:09 110559 8192000000 10309119568 10781589504 1368981184 14.318 97 14 28 0 1390506112 17783888 3741040
16:28:24 62971 8192000000 10291397328 10756280320 1368437072 14.313 98 13 26 0 1385003008 13924096 2641840
16:30:28 39495 8192000000 10274813440 10735308800 1360591136 14.243 98 7 14 0 1367733136 5712400 1429600
16:32:07 53654 8192000000 10299488800 10761936896 1390640960 14.512 99 20 41 0 1404116096 8854032 4621104
16:34:02 54637 8192000000 10281111408 10751451136 1370130288 14.328 98 14 29 0 1382620272 8977904 3512080
16:36:13 36803 8192000000 10261196064 10721030144 1362668592 14.261 98 14 28 0 1373833952 8097120 3068240
16:37:59 45960 8192000000 9675782960 10042855424 807465168 8.972 58 4 8 0 814286144 6196048 624928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-12-2022 16:40:16 to 28-12-2022 16:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2022 16:40:32 to 28-12-2022 16:40:34.

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          238705
MON$OLDEST_ACTIVE               238706
MON$OLDEST_SNAPSHOT             238706
MON$NEXT_TRANSACTION            238706
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                       2005568
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
--- end of diff output ---

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

28.12.22 16:40:35. 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-28 16:40:38 No severe PSQL-related problems occured
28.12.22 16:40:39. End of report.