Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2022 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 15589208 kB
MemAvailable 30572144 kB
Buffers 0 kB
Cached 16154764 kB
SwapCached 2172 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-10-06 22:38:31
DTS_END                         2022-10-06 22:38:31.0850
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/34784
TRN_ID                          243564
ATT_ID                          714
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: 9193.03 17688 1103164
customer order: creation 177.14 362 21257
customer order: refuse 14.88 357 1785
order to supplier: creation 46.85 1266 5622
order to supplier: removal 3.40 102897 408
invoice (draft): creation 46.62 3037 5594
invoice (draft): removal 53.48 800 6418
invoice accept: apply 41.14 16915 4937
invoice accept: cancel 48.00 839 5760
customer reserve: creation 3743.48 169 449218
customer reserve: removal 4705.72 58 564686
realization accept: apply 85.97 176 10316
realization accept: cancel 93.83 108 11260
payment from customer: creation 61.59 122 7391
payment from customer: removal 5.71 81 685
payment to supplier: creation 49.23 120 5908
payment to supplier: removal 3.49 73 419
service: total inventory turnovers 5.00 1063 600
service: total monetary turnovers 6.69 30 803
service: aggregate perf. data 0.78 24589 93
service: refresh index statistics 0.03 200695 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-10-2022 22:40:20 to 06-10-2022 22:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3145
TEST_TIME 2 4119
TEST_TIME 3 5133
TEST_TIME 4 5348
TEST_TIME 5 4944
TEST_TIME 6 5522
TEST_TIME 7 5257
TEST_TIME 8 5032
TEST_TIME 9 5216
TEST_TIME 10 5946
TEST_TIME 11 6450
TEST_TIME 12 6558
TEST_TIME 13 6698
TEST_TIME 14 7110
TEST_TIME 15 7629
TEST_TIME 16 7668
TEST_TIME 17 8099
TEST_TIME 18 8204
TEST_TIME 19 8338
TEST_TIME 20 8556
TEST_TIME 21 9057
TEST_TIME 22 9058
TEST_TIME 23 9195
TEST_TIME 24 9612
TEST_TIME 25 9801
TEST_TIME 26 9826
TEST_TIME 27 10663
TEST_TIME 28 11279
TEST_TIME 29 11165
TEST_TIME 30 11006
TEST_TIME 31 10755
TEST_TIME 32 10536
TEST_TIME 33 10648
TEST_TIME 34 10652
TEST_TIME 35 10485
TEST_TIME 36 10361
TEST_TIME 37 10245
TEST_TIME 38 10127
TEST_TIME 39 9978
TEST_TIME 40 10048
TEST_TIME 41 9910
TEST_TIME 42 9965
TEST_TIME 43 9818
TEST_TIME 44 9934
TEST_TIME 45 9806
TEST_TIME 46 9772
TEST_TIME 47 9664
TEST_TIME 48 9599
TEST_TIME 49 9437
TEST_TIME 50 9450
TEST_TIME 51 9554
TEST_TIME 52 9526
TEST_TIME 53 9472
TEST_TIME 54 9442
TEST_TIME 55 9509
TEST_TIME 56 9529
TEST_TIME 57 9473
TEST_TIME 58 9648
TEST_TIME 59 9642
TEST_TIME 60 9660
TEST_TIME 61 9717
TEST_TIME 62 9766
TEST_TIME 63 9749
TEST_TIME 64 9851
TEST_TIME 65 9944
TEST_TIME 66 9817
TEST_TIME 67 9753
TEST_TIME 68 9710
TEST_TIME 69 9678
TEST_TIME 70 9662
TEST_TIME 71 9669
TEST_TIME 72 9617
TEST_TIME 73 9552
TEST_TIME 74 9484
TEST_TIME 75 9504
TEST_TIME 76 9545
TEST_TIME 77 9566
TEST_TIME 78 9481
TEST_TIME 79 9427
TEST_TIME 80 9406
TEST_TIME 81 9403
TEST_TIME 82 9356
TEST_TIME 83 9386
TEST_TIME 84 9375
TEST_TIME 85 9324
TEST_TIME 86 9355
TEST_TIME 87 9434
TEST_TIME 88 9467
TEST_TIME 89 9452
TEST_TIME 90 9533
TEST_TIME 91 9527
TEST_TIME 92 9595
TEST_TIME 93 9597
TEST_TIME 94 9583
TEST_TIME 95 9499
TEST_TIME 96 9437
TEST_TIME 97 9400
TEST_TIME 98 9365
TEST_TIME 99 9397
TEST_TIME 100 9362
TEST_TIME 101 9327
TEST_TIME 102 9327
TEST_TIME 103 9361
TEST_TIME 104 9338
TEST_TIME 105 9348
TEST_TIME 106 9331
TEST_TIME 107 9323
TEST_TIME 108 9379
TEST_TIME 109 9370
TEST_TIME 110 9385
TEST_TIME 111 9463
TEST_TIME 112 9447
TEST_TIME 113 9417
TEST_TIME 114 9383
TEST_TIME 115 9346
TEST_TIME 116 9305
TEST_TIME 117 9285
TEST_TIME 118 9268
TEST_TIME 119 9252
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2022 22:40:24 to 06-10-2022 22:40:27.

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 1522855 1522855 0 0.00 0 21389 6 0 0
doc_list_biud 1522854 1522854 0 0.00 0 61706 28 0 0
sp_add_invoice_to_stock 5077 4937 140 2.75 33 152139 16916 0 140
sp_cancel_adding_invoice 5760 5760 0 0.00 4 61974 839 0 0
sp_cancel_client_order 1785 1785 0 0.00 4 17160 357 0 0
sp_cancel_customer_reserve 564686 564686 0 0.00 0 44129 59 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 5625 82 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 3376 73 0 0
sp_cancel_supplier_invoice 6430 6418 12 0.18 1 61771 801 0 12
sp_cancel_supplier_order 408 408 0 0.00 6 960895 102898 0 0
sp_cancel_write_off 11260 11260 0 0.00 1 21390 109 0 0
sp_client_order 21257 21257 0 0.00 3 38061 363 0 0
sp_customer_reserve 449218 449218 0 0.00 0 38510 170 0 0
sp_fill_shopping_cart 32460 32460 0 0.00 1 24796 39 0 0
sp_fill_shopping_cart_clo_res 449214 449214 0 0.00 0 15389 14 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 25509 313 0 0
sp_kill_cost_storno 18124 18124 0 0.00 0 21388 54 0 0
sp_kill_qstorno_ret_qs2qd 1147458 1147458 0 0.00 0 61704 32 0 0
sp_kill_qty_storno 605560 605560 0 0.00 0 61706 69 0 0
sp_lock_selected_doc 21100 21100 0 0.00 0 7699 5 0 0
sp_make_cost_storno 28542 28542 0 0.00 0 18442 52 0 0
sp_multiply_rows_for_pdistr 28542 28542 0 0.00 0 14214 27 0 0
sp_multiply_rows_for_qdistr 21257 21257 0 0.00 0 38048 172 0 0
sp_pay_from_customer 7391 7391 0 0.00 4 15292 122 0 0
sp_pay_to_supplier 5908 5908 0 0.00 4 17765 120 0 0
sp_payment_common 13299 13299 0 0.00 1 13952 32 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 17154 329 0 0
sp_qd_handle_on_invoice_upd_sts 10687 10687 0 0.00 0 16989 317 0 0
sp_qd_handle_on_reserve_upd_sts 10316 10316 0 0.00 0 18367 59 0 0
sp_reserve_write_off 10316 10316 0 0.00 6 18411 176 0 0
sp_supplier_invoice 5594 5594 0 0.00 6 65292 3037 0 0
sp_supplier_order 5622 5622 0 0.00 5 71933 1267 0 0
srv_aggregate_perf_data 93 93 0 0.00 2663 707722 24590 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2337 148651 45668 0 0
srv_find_qd_qs_mism 1057014 1057014 0 0.00 0 18503 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 32 75547 1064 0 0
srv_make_money_saldo 803 803 0 0.00 0 2867 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 114763 323211 200696 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4937 4937 0 0.00 0 18285 157 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 3511 96 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 1294 8 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 3656 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 2746 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 172 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1371 11 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 30763 1443 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 939 4 0 0
t$perf-norm:sp_client_order 21258 21258 0 0.00 0 11085 19 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 1409 8 0 0
t$perf-norm:sp_pay_from_customer 7391 7391 0 0.00 0 6908 11 0 0
t$perf-norm:sp_pay_to_supplier 5908 5908 0 0.00 0 6315 10 0 0
t$perf-norm:sp_reserve_write_off 10316 10316 0 0.00 0 6601 12 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 6401 15 0 0
t$perf-norm:sp_supplier_order 5622 5622 0 0.00 0 2483 12 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1518 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1051 19 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 2342 23 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 709 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10134 9854 280 2.76 0 15572 20 0 280
v_all_customers 39032 39032 0 0.00 0 22 1 0 0
v_all_suppliers 31128 31128 0 0.00 0 23 1 0 0
v_all_wares 42514 42514 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 14886 46 0 0
v_cancel_client_order 3570 3570 0 0.00 0 2901 5 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 5589 10 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 5885 10 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 3355 8 0 0
v_cancel_supplier_invoice 880 856 24 2.72 0 1558 17 0 24
v_cancel_supplier_order 816 816 0 0.00 0 5883 10 0 0
v_cancel_write_off 2058 2058 0 0.00 0 18192 21 0 0
v_max_id_clo_ord 5622 5622 0 0.00 0 13579 4 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 2907 26 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 4132 3 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 862 3 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 1620 4 0 0
v_min_id_clo_ord 5622 5622 0 0.00 0 6184 7 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 5540 12 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 8214 11 0 0
v_min_non_paid_invoice 5908 5908 0 0.00 0 13941 26 0 0
v_min_non_paid_realizn 7391 7391 0 0.00 0 9710 23 0 0
v_our_firm 10622 10622 0 0.00 0 26 1 0 0
v_reserve_write_off 20632 20632 0 0.00 0 13575 8 0 0
x_lock_depdocs_on_canc_invoice 11750 11750 0 0.00 0 11659 126 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 11244 442 0 0
x_make_qstorno_1000_1200 5622 5622 0 0.00 1 71921 1187 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 3 65284 2895 0 0
x_make_qstorno_2100_3300 449218 449218 0 0.00 0 29452 154 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-10-2022 22:40:28 to 06-10-2022 22:40:33.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:35 21604 8192000000 9879038640 10185375744 961820256 10.507 100 85 170 0 990065008 5569328 22675424
20:11:03 16221 8192000000 9923879056 10241708032 1008599264 10.962 100 94 188 0 1042198032 8707424 24891344
20:12:28 21201 8192000000 9970106784 10301235200 1048480768 11.346 100 81 162 0 1078299632 9603376 20215488
20:13:55 8154 8192000000 10035806992 10379825152 1116180992 11.991 100 82 165 0 1146725024 11001344 19542688
20:15:06 6646 8192000000 10069854768 10426109952 1156973328 12.375 100 79 158 0 1186524608 12196064 17355216
20:16:16 3657 8192000000 10123143088 10489352192 1213532528 12.902 100 77 154 2 1242182608 11554560 17095520
20:17:21 2501 8192000000 10148590480 10520494080 1240794544 13.154 100 85 170 1 1272742416 13906048 18041824
20:18:25 3294 8192000000 10183972464 10564612096 1272493120 13.444 100 73 144 1 1300897232 12937888 15466224
20:19:31 1297 8192000000 10218389024 10602954752 1303417584 13.726 100 91 179 0 1335680240 13007344 19255312
20:20:35 7652 8192000000 10199951584 10606428160 1307794320 13.766 100 41 80 0 1324517008 7874752 8847936
20:21:44 4462 8192000000 10205099328 10616635392 1317142352 13.851 100 28 56 0 1330074576 6724944 6207280
20:22:52 45762 8192000000 10215781344 10635636736 1324674880 13.919 100 47 94 1 1341376848 5335472 11366496
20:24:43 8280 8192000000 10225446992 10643275776 1333059568 13.995 100 20 40 0 1343215344 5567712 4588064
20:25:52 9203 8192000000 10229673536 10654289920 1341688352 14.073 100 20 38 0 1352645728 5881104 5076272
20:27:05 73682 8192000000 10210249600 10629054464 1300238608 13.697 97 30 59 1 1312356416 5600384 6517424
20:29:19 7497 8192000000 10182226384 10596126720 1275417664 13.471 98 34 66 0 1289622032 7073808 7130560
20:30:26 6899 8192000000 10157093072 10558484480 1227610960 13.032 96 24 48 0 1238425808 5523152 5291696
20:31:36 13108 8192000000 10138548880 10528493568 1198580464 12.763 95 48 93 3 1216303728 5588928 12134336
20:33:31 6636 8192000000 10154239360 10555584512 1261516720 13.344 100 33 67 0 1280644096 11648256 7479120
20:34:37 8853 8192000000 10172549632 10583953408 1285357216 13.562 100 19 38 0 1295146976 5158400 4631360
20:35:50 13596 8192000000 10188203936 10598985728 1295146688 13.651 100 13 26 1 1303222368 5182000 2893680
20:37:06 16196 8192000000 10212279104 10610413568 1302705440 13.720 100 62 127 2 1323886736 6347504 14833792
20:38:23 6301 8192000000 10208497488 10624823296 1320755392 13.884 100 20 40 1 1330498832 4948128 4795312
20:39:36 15470 8192000000 10250224432 10658033664 1342638512 14.081 100 55 108 2 1370031728 15242608 12150608
20:40:56 7153 8192000000 10263873424 10680733696 1358492080 14.224 100 38 73 0 1380923760 14150624 8281056
20:42:05 48567 8192000000 10286231888 10710347776 1376151312 14.382 100 50 99 0 1404413056 16776288 11485456
20:44:01 53739 8192000000 10299228128 10733420544 1390186496 14.508 100 24 48 1 1415940800 19412624 6341680
20:46:01 7898 8192000000 10318807904 10741633024 1399348560 14.589 100 54 107 0 1430291088 18677632 12264896
20:47:17 2336 8192000000 10331752176 10747293696 1397267376 14.571 100 66 128 1 1437420752 24784800 15368576
20:48:28 3029 8192000000 10341809088 10753273856 1394465488 14.546 99 78 149 0 1439927312 27640512 17821312
20:49:35 2474 8192000000 10358192352 10775224320 1415747008 14.735 100 73 144 0 1467689728 34299840 17642880
20:50:45 4252 8192000000 10350824128 10764574720 1395943936 14.559 100 75 151 2 1454500720 39268848 19287936
20:51:53 3103 8192000000 10329981280 10740965376 1354593536 14.189 97 76 152 3 1416506304 42574640 19338128
20:53:00 10352 8192000000 10357608464 10777198592 1401739744 14.610 100 65 129 1 1463060240 45288240 16032256
20:54:18 4252 8192000000 10330560448 10749136896 1369579952 14.323 99 57 111 0 1427956080 44244192 14131936
20:55:30 3125 8192000000 10342675216 10758447104 1388859664 14.496 100 63 124 0 1446009392 41327808 15821920
20:56:40 9736 8192000000 10301852096 10721251328 1354000592 14.183 99 36 72 0 1400002160 36162208 9839360
20:57:52 17486 8192000000 10297489856 10719875072 1342572720 14.081 99 41 80 0 1402489472 49517920 10398832
20:59:12 2885 8192000000 10319634752 10729676800 1363141680 14.266 100 56 112 0 1418834800 40557824 15135296
21:00:20 12493 8192000000 10318129328 10748940288 1374077376 14.364 100 32 64 0 1417709408 34730032 8902000
21:01:34 19294 8192000000 10321414560 10745757696 1376339712 14.384 100 34 68 0 1425826848 39899456 9587680
21:02:58 10981 8192000000 10295398112 10721714176 1354060192 14.184 100 24 48 0 1400710768 39700160 6950416
21:04:14 23547 8192000000 10294756464 10734567424 1354419504 14.187 100 22 44 1 1403653968 42871504 6362960
21:05:40 41141 8192000000 10292938864 10736783360 1344976912 14.102 99 15 28 1 1380765952 32366880 3422160
21:07:23 51137 8192000000 10272966416 10717143040 1355033360 14.193 99 35 70 0 1377266672 14245824 7987488
21:09:15 13740 8192000000 10286180608 10720604160 1363187392 14.266 99 36 70 1 1381693296 10792800 7713104
21:10:30 16873 8192000000 10289325744 10722709504 1361579904 14.252 99 48 95 0 1385943280 12610528 11752848
21:11:54 93151 8192000000 10274805008 10729988096 1365907440 14.290 99 10 21 0 1378928880 10688304 2333136
21:14:27 6298 8192000000 10268560016 10720456704 1371066752 14.337 100 15 33 0 1381419328 7176128 3176448
21:15:43 38300 8192000000 10292698864 10739609600 1382859040 14.442 100 19 38 0 1396434192 9517264 4057888
21:17:24 31591 8192000000 10303566416 10755874816 1399003424 14.586 100 14 28 0 1415326880 12727184 3596272
21:18:58 47814 8192000000 10293407296 10763022336 1370875808 14.335 98 16 32 1 1389849872 15139712 3834352
21:22:01 99057 8192000000 10295200240 10761797632 1403013312 14.622 100 20 40 0 1418996400 11788016 4195072
21:24:41 43846 8192000000 10283269312 10750877696 1371537968 14.341 98 4 8 0 1382321504 10007424 776112
21:26:25 60806 8192000000 10307495872 10769354752 1393112816 14.534 99 21 42 0 1414782976 16116448 5553712
21:28:47 49489 8192000000 10295644880 10781155328 1408095920 14.667 100 14 28 0 1425467584 14210112 3161552
21:30:50 57520 8192000000 10317696528 10774134784 1409895488 14.683 100 17 32 0 1424833584 11544368 3393728
21:32:48 44238 8192000000 10312228496 10772639744 1404028528 14.631 99 22 44 0 1423754352 14717664 5008160
21:34:38 28517 8192000000 10332107824 10784968704 1395105776 14.551 98 25 50 0 1414850288 14396144 5348368
21:36:13 73823 8192000000 10281380816 10738688000 1306442432 13.754 93 24 48 1 1321803184 10134144 5226608
21:38:27 62046 8192000000 10290061264 10741739520 1350432016 14.151 97 20 40 0 1364004832 9169264 4403552
21:40:29 58571 8192000000 10294221664 10746781696 1367494112 14.305 99 14 28 0 1382587808 12366960 2726736
21:42:42 71765 8192000000 10223457104 10675281920 1257724176 13.309 92 14 28 0 1274662976 13834656 3104144
21:44:55 108525 8192000000 10259520880 10713903104 1321530848 13.891 97 38 76 0 1339105024 8678144 8896032
21:47:44 32330 8192000000 10265545680 10710052864 1347219600 14.122 99 35 72 1 1363967216 9093744 7653872
21:49:18 75690 8192000000 10262589568 10722648064 1357922208 14.219 100 14 28 1 1378062688 16481680 3658800
21:51:38 79605 8192000000 10286951424 10738167808 1381535872 14.430 100 25 50 0 1404296000 16699712 6060416
21:53:58 69420 8192000000 10270935424 10725658624 1375369632 14.375 100 15 31 0 1393091632 13920176 3801824
21:56:15 65200 8192000000 10271906832 10720661504 1362033056 14.256 99 23 48 0 1374134160 6960000 5141104
21:58:35 71247 8192000000 10267329008 10737819648 1357963520 14.219 99 2 4 0 1370524176 12220400 340256
22:00:48 48626 8192000000 10274617280 10727956480 1360996672 14.246 99 19 38 0 1373244512 8108720 4139120
22:02:42 23217 8192000000 10273383296 10725351424 1354846224 14.191 98 23 46 1 1369575056 9607680 5121152
22:04:10 79250 8192000000 10274041936 10740383744 1342846000 14.083 97 11 22 0 1364378304 18666288 2866016
22:06:32 20610 8192000000 10283065424 10727870464 1356355024 14.205 98 28 56 1 1371807168 9498016 5954128
22:07:56 47677 8192000000 10308476464 10753863680 1361313440 14.249 98 37 74 0 1386547904 17164224 8070240
22:09:44 81592 8192000000 10314011984 10779635712 1382876048 14.442 99 21 40 1 1398810960 11519248 4415664
22:12:06 148651 8192000000 10290149696 10748858368 1372926288 14.353 98 18 34 0 1388525024 11319232 4279504
22:15:44 64110 8192000000 10288371824 10753683456 1372760208 14.352 98 10 20 0 1389961040 14726640 2474192
22:17:52 46149 8192000000 10280886304 10753740800 1377713040 14.396 99 10 20 0 1391552304 11709968 2129296
22:19:40 44630 8192000000 10278380048 10738794496 1341989328 14.075 96 10 21 0 1350404048 6279744 2134976
22:21:28 70366 8192000000 10277353264 10733727744 1328768848 13.956 95 11 23 0 1339634384 8784880 2080656
22:23:51 80858 8192000000 10295218368 10751279104 1343447552 14.088 96 17 35 0 1357172416 9782080 3942784
22:26:36 82074 8192000000 10277670144 10723602432 1278651600 13.501 92 13 26 0 1290459536 9031760 2776176
22:29:05 102150 8192000000 10242413440 10680033280 1201806304 12.793 89 6 12 0 1214193984 11433008 954672
22:31:48 113839 8192000000 10253941920 10703880192 1345199872 14.104 99 37 72 0 1363031808 8838448 8993488
22:34:42 72374 8192000000 10237605984 10694242304 1290490400 13.609 95 10 20 0 1303927152 11024976 2411776
22:36:56 42577 8192000000 10265604688 10727260160 1349675248 14.145 100 5 10 0 1365591504 14814000 1102256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-10-2022 22:40:35 to 06-10-2022 22:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2022 22:40:51 to 06-10-2022 22:40:53.

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          242246
MON$OLDEST_ACTIVE               243835
MON$OLDEST_SNAPSHOT             243835
MON$NEXT_TRANSACTION            243835
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                       2022208
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:304: 
:305: mscs-test-161.budzdorov.ru    Thu Oct  6 22:38:37 2022
:306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:307: 
:308: 
:309: mscs-test-161.budzdorov.ru    Thu Oct  6 22:38:41 2022
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:311: 
:312: 
:313: mscs-test-161.budzdorov.ru    Thu Oct  6 22:38:45 2022
:314:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:315: 
:316: 
:317: mscs-test-161.budzdorov.ru    Thu Oct  6 22:38:45 2022
:318:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:319: 
:320: 
:321: mscs-test-161.budzdorov.ru    Thu Oct  6 22:38:45 2022
:322:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:323: 
--- end of diff output ---

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

06.10.22 22:40:54. 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-10-06 22:40:56 No severe PSQL-related problems occured
06.10.22 22:40:58. End of report.