Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 15550100 kB
MemAvailable 30548284 kB
Buffers 0 kB
Cached 16189612 kB
SwapCached 2076 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-11 22:38:31
DTS_END                         2022-10-11 22:38:31.8360
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/55458
TRN_ID                          243965
ATT_ID                          728
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: 9467.91 20383 1136149
customer order: creation 179.48 330 21537
customer order: refuse 14.80 348 1776
order to supplier: creation 46.93 1256 5631
order to supplier: removal 3.53 94890 424
invoice (draft): creation 46.63 3139 5596
invoice (draft): removal 54.27 856 6512
invoice accept: apply 42.87 16817 5144
invoice accept: cancel 48.43 821 5811
customer reserve: creation 3949.06 167 473887
customer reserve: removal 4773.11 54 572773
realization accept: apply 86.99 162 10439
realization accept: cancel 86.42 107 10370
payment from customer: creation 61.79 102 7415
payment from customer: removal 6.19 86 743
payment to supplier: creation 51.40 116 6168
payment to supplier: removal 3.74 74 449
service: total inventory turnovers 4.51 1386 541
service: total monetary turnovers 6.93 34 831
service: aggregate perf. data 0.82 24376 98
service: refresh index statistics 0.03 262539 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2022 22:40:04 to 11-10-2022 22:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4226
TEST_TIME 2 3857
TEST_TIME 3 3234
TEST_TIME 4 4391
TEST_TIME 5 5008
TEST_TIME 6 6099
TEST_TIME 7 6241
TEST_TIME 8 6527
TEST_TIME 9 6243
TEST_TIME 10 6284
TEST_TIME 11 6244
TEST_TIME 12 6360
TEST_TIME 13 6482
TEST_TIME 14 6433
TEST_TIME 15 6771
TEST_TIME 16 6805
TEST_TIME 17 7149
TEST_TIME 18 7372
TEST_TIME 19 7509
TEST_TIME 20 8294
TEST_TIME 21 8444
TEST_TIME 22 8322
TEST_TIME 23 8583
TEST_TIME 24 8927
TEST_TIME 25 9023
TEST_TIME 26 9161
TEST_TIME 27 10238
TEST_TIME 28 10760
TEST_TIME 29 11027
TEST_TIME 30 10912
TEST_TIME 31 10726
TEST_TIME 32 10588
TEST_TIME 33 10305
TEST_TIME 34 10355
TEST_TIME 35 10458
TEST_TIME 36 10462
TEST_TIME 37 10388
TEST_TIME 38 10396
TEST_TIME 39 10240
TEST_TIME 40 10094
TEST_TIME 41 10147
TEST_TIME 42 10011
TEST_TIME 43 10108
TEST_TIME 44 10126
TEST_TIME 45 9993
TEST_TIME 46 9936
TEST_TIME 47 9940
TEST_TIME 48 9821
TEST_TIME 49 9791
TEST_TIME 50 9740
TEST_TIME 51 9682
TEST_TIME 52 9601
TEST_TIME 53 9548
TEST_TIME 54 9681
TEST_TIME 55 9770
TEST_TIME 56 9689
TEST_TIME 57 9922
TEST_TIME 58 9871
TEST_TIME 59 9820
TEST_TIME 60 9883
TEST_TIME 61 9894
TEST_TIME 62 9938
TEST_TIME 63 9920
TEST_TIME 64 10035
TEST_TIME 65 10049
TEST_TIME 66 10008
TEST_TIME 67 9913
TEST_TIME 68 9912
TEST_TIME 69 9879
TEST_TIME 70 9849
TEST_TIME 71 9839
TEST_TIME 72 9752
TEST_TIME 73 9679
TEST_TIME 74 9723
TEST_TIME 75 9689
TEST_TIME 76 9689
TEST_TIME 77 9652
TEST_TIME 78 9679
TEST_TIME 79 9718
TEST_TIME 80 9733
TEST_TIME 81 9710
TEST_TIME 82 9659
TEST_TIME 83 9756
TEST_TIME 84 9824
TEST_TIME 85 9799
TEST_TIME 86 9825
TEST_TIME 87 9804
TEST_TIME 88 9752
TEST_TIME 89 9713
TEST_TIME 90 9684
TEST_TIME 91 9640
TEST_TIME 92 9671
TEST_TIME 93 9590
TEST_TIME 94 9561
TEST_TIME 95 9556
TEST_TIME 96 9567
TEST_TIME 97 9549
TEST_TIME 98 9612
TEST_TIME 99 9601
TEST_TIME 100 9643
TEST_TIME 101 9666
TEST_TIME 102 9635
TEST_TIME 103 9624
TEST_TIME 104 9663
TEST_TIME 105 9670
TEST_TIME 106 9742
TEST_TIME 107 9750
TEST_TIME 108 9718
TEST_TIME 109 9774
TEST_TIME 110 9692
TEST_TIME 111 9669
TEST_TIME 112 9634
TEST_TIME 113 9634
TEST_TIME 114 9597
TEST_TIME 115 9547
TEST_TIME 116 9529
TEST_TIME 117 9535
TEST_TIME 118 9492
TEST_TIME 119 9527
TEST_TIME 120 9470

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2022 22:40:08 to 11-10-2022 22:40:11.

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 1576542 1576542 0 0.00 0 17080 6 0 0
doc_list_biud 1576541 1576541 0 0.00 0 22558 26 0 0
sp_add_invoice_to_stock 5253 5144 109 2.07 35 193697 16817 0 109
sp_cancel_adding_invoice 5811 5811 0 0.00 4 68480 822 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 17760 348 0 0
sp_cancel_customer_reserve 572773 572773 0 0.00 0 22561 55 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 2 7167 87 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 2 7076 75 0 0
sp_cancel_supplier_invoice 6525 6512 13 0.19 2 21866 856 0 13
sp_cancel_supplier_order 424 424 0 0.00 6 1243058 94890 0 0
sp_cancel_write_off 10370 10370 0 0.00 1 17764 107 0 0
sp_client_order 21537 21537 0 0.00 3 22084 330 0 0
sp_customer_reserve 473887 473887 0 0.00 0 76684 168 0 0
sp_fill_shopping_cart 32745 32745 0 0.00 1 16378 37 0 0
sp_fill_shopping_cart_clo_res 473885 473885 0 0.00 0 16520 14 0 0
sp_get_clo_for_invoice 5142 5142 0 0.00 0 15494 270 0 0
sp_kill_cost_storno 17373 17373 0 0.00 0 16242 53 0 0
sp_kill_qstorno_ret_qs2qd 1162852 1162852 0 0.00 0 22554 30 0 0
sp_kill_qty_storno 613246 613246 0 0.00 0 22558 66 0 0
sp_lock_selected_doc 21637 21637 0 0.00 0 9618 5 0 0
sp_make_cost_storno 29164 29164 0 0.00 0 17075 49 0 0
sp_multiply_rows_for_pdistr 29163 29163 0 0.00 0 10384 26 0 0
sp_multiply_rows_for_qdistr 21537 21537 0 0.00 0 14629 158 0 0
sp_pay_from_customer 7415 7415 0 0.00 4 13370 103 0 0
sp_pay_to_supplier 6168 6168 0 0.00 5 12798 117 0 0
sp_payment_common 13583 13583 0 0.00 1 11534 32 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 17746 329 0 0
sp_qd_handle_on_invoice_upd_sts 10952 10952 0 0.00 0 18751 304 0 0
sp_qd_handle_on_reserve_upd_sts 10439 10439 0 0.00 0 22415 57 0 0
sp_reserve_write_off 10439 10439 0 0.00 6 22498 162 0 0
sp_supplier_invoice 5596 5596 0 0.00 5 75561 3139 0 0
sp_supplier_order 5631 5631 0 0.00 5 46279 1256 0 0
srv_aggregate_perf_data 98 98 0 0.00 3081 737978 24376 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1112 137599 45716 0 0
srv_find_qd_qs_mism 1087515 1087515 0 0.00 0 11722 5 0 0
srv_make_invnt_saldo 541 541 0 0.00 41 116545 1386 0 0
srv_make_money_saldo 831 831 0 0.00 1 6478 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 116046 432894 262539 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5144 5144 0 0.00 0 12100 155 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 4348 83 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 3653 13 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 1639 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 4205 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 3885 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 9887 41 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 21995 1449 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 4531 9 0 0
t$perf-norm:sp_client_order 21537 21537 0 0.00 0 9354 19 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 5217 12 0 0
t$perf-norm:sp_pay_from_customer 7415 7415 0 0.00 0 4839 12 0 0
t$perf-norm:sp_pay_to_supplier 6168 6168 0 0.00 0 10142 18 0 0
t$perf-norm:sp_reserve_write_off 10439 10439 0 0.00 0 10579 11 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 8708 27 0 0
t$perf-norm:sp_supplier_order 5631 5631 0 0.00 0 6122 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 183 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 395 7 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 5318 32 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 4828 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10498 10280 218 2.07 0 9126 19 0 218
v_all_customers 39276 39276 0 0.00 0 35 1 0 0
v_all_suppliers 31676 31676 0 0.00 0 36 1 0 0
v_all_wares 43070 43070 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 5986 14 0 0
v_cancel_client_order 3552 3552 0 0.00 0 1412 3 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 2578 7 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 784 5 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 7059 11 0 0
v_cancel_supplier_invoice 884 858 26 2.94 0 341 12 0 26
v_cancel_supplier_order 848 848 0 0.00 0 2459 7 0 0
v_cancel_write_off 2094 2094 0 0.00 0 6623 15 0 0
v_max_id_clo_ord 5631 5631 0 0.00 0 1519 2 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2092 10 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 6160 5 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 221 3 0 0
v_max_non_paid_realizn 4039 4039 0 0.00 0 5430 5 0 0
v_min_id_clo_ord 5631 5631 0 0.00 0 4073 9 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 5777 14 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 4653 6 0 0
v_min_non_paid_invoice 6168 6168 0 0.00 0 11527 24 0 0
v_min_non_paid_realizn 7415 7415 0 0.00 0 10633 26 0 0
v_our_firm 10546 10546 0 0.00 0 35 1 0 0
v_reserve_write_off 20878 20878 0 0.00 0 3949 5 0 0
x_lock_depdocs_on_canc_invoice 11894 11894 0 0.00 0 16152 100 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 8226 329 0 0
x_make_qstorno_1000_1200 5631 5631 0 0.00 1 46267 1183 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 2 75537 2999 0 0
x_make_qstorno_2100_3300 473887 473887 0 0.00 0 76683 151 0 0

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

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 8797390080 8808136704 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 16429 8192000000 9879657248 10182733824 961721040 10.506 100 87 174 0 990136944 5578064 22837840
20:10:58 20627 8192000000 9928545808 10239856640 1010536112 10.981 100 95 190 0 1042821168 7353856 24931200
20:12:26 19737 8192000000 9969824864 10299551744 1050674800 11.367 100 80 160 0 1079114848 8487744 19952304
20:13:57 11451 8192000000 10015440944 10360967168 1103695952 11.873 100 74 148 0 1131434416 10093728 17644736
20:15:14 3559 8192000000 10070556240 10425765888 1159801776 12.401 100 77 154 0 1189040912 11533552 17705584
20:16:19 4222 8192000000 10110453552 10478559232 1203720512 12.811 100 76 150 0 1232949536 12613616 16615408
20:17:31 4290 8192000000 10157532800 10532929536 1239442768 13.141 100 73 146 0 1268998768 13285616 16270384
20:18:37 2319 8192000000 10187610512 10568105984 1274653344 13.464 100 85 169 1 1306629664 12787312 19189008
20:19:42 1090 8192000000 10202418176 10592366592 1296103568 13.660 100 78 156 0 1326713968 12750112 17860288
20:20:45 865 8192000000 10232021488 10619064320 1313799424 13.821 100 84 170 1 1344841712 12861264 18181024
20:21:47 5025 8192000000 10207941360 10614427648 1317329424 13.853 100 45 87 3 1335170096 6503856 11336816
20:22:53 4122 8192000000 10215808528 10623918080 1322242848 13.897 100 39 78 0 1337232016 6382624 8606544
20:23:59 10086 8192000000 10212236736 10628460544 1326609056 13.937 100 18 36 0 1336338080 6076800 3652224
20:25:12 22970 8192000000 10219131328 10642997248 1331512256 13.981 100 26 53 0 1343386272 5610512 6263504
20:27:22 4669 8192000000 10238002848 10655350784 1340712432 14.064 100 36 73 1 1359222384 9971968 8537984
20:28:30 8589 8192000000 10225994016 10646605824 1339890400 14.056 100 24 47 2 1350491792 6123328 4478064
20:29:44 8487 8192000000 10205463808 10607255552 1279787184 13.511 97 32 61 1 1295693088 8939120 6966784
20:30:55 19235 8192000000 10154637632 10569011200 1226054816 13.018 94 15 30 1 1235168032 5555008 3558208
20:32:18 6549 8192000000 10127369088 10524745728 1217197600 12.936 98 33 66 1 1231147968 5622256 8328112
20:33:26 8096 8192000000 10150678544 10551037952 1249584512 13.234 99 37 74 1 1263785744 4898432 9302800
20:34:35 16190 8192000000 10166372144 10564554752 1249449536 13.233 98 33 63 1 1263341152 6276176 7615440
20:35:52 14669 8192000000 10177360400 10584248320 1281539216 13.527 100 27 54 0 1293925136 6338576 6047344
20:37:09 10277 8192000000 10191115376 10601095168 1295601824 13.655 100 16 32 0 1304531104 5368096 3561184
20:38:20 11261 8192000000 10209680064 10621566976 1315497504 13.836 100 22 44 0 1325277408 4729184 5050720
20:39:53 48871 8192000000 10251490768 10665672704 1343652240 14.090 100 48 96 0 1364584016 9691840 11239936
20:41:49 55268 8192000000 10260339568 10685771776 1353833280 14.182 100 31 62 0 1372530560 11637408 7059872
20:43:54 7119 8192000000 10281690208 10696675328 1366093984 14.292 100 60 119 0 1395959408 16471680 13393744
20:45:04 21043 8192000000 10280342112 10706608128 1371562608 14.341 100 42 84 0 1398156032 15637648 10955776
20:46:35 5697 8192000000 10301407024 10724540416 1388258528 14.490 100 46 90 0 1414601120 15564240 10778352
20:47:46 32882 8192000000 10326008576 10750787584 1402014448 14.613 100 49 98 0 1432375280 18950480 11410352
20:49:26 17272 8192000000 10339316736 10768027648 1419049328 14.764 100 50 97 0 1450904736 20407440 11447968
20:50:51 1992 8192000000 10358001200 10778058752 1420437024 14.777 100 79 154 1 1465007552 25826336 18744192
20:51:59 9647 8192000000 10351210112 10774065152 1417392368 14.750 100 61 118 1 1457944480 26288656 14263456
20:53:16 3945 8192000000 10362268064 10779574272 1413005744 14.711 100 70 139 1 1464398176 33956304 17436128
20:54:26 3503 8192000000 10348655264 10765119488 1403032992 14.622 100 80 154 3 1453788800 32314832 18440976
20:55:35 2638 8192000000 10350846736 10763436032 1393372560 14.536 99 71 137 1 1451502256 40912720 17216976
20:56:44 4146 8192000000 10354871152 10770857984 1399683456 14.592 100 66 129 2 1458270128 41758976 16827696
20:58:05 1110 8192000000 10360009088 10761629696 1391670192 14.521 100 92 179 1 1463139344 48864896 22604256
20:59:13 2968 8192000000 10335726128 10751381504 1388888448 14.496 100 49 99 1 1439516704 37212112 13416144
21:00:18 4229 8192000000 10342886048 10763943936 1396090624 14.560 100 46 92 0 1447942032 39259184 12592224
21:01:28 6249 8192000000 10352715600 10778677248 1402904816 14.621 100 43 84 0 1457736608 43148192 11683600
21:02:36 17533 8192000000 10341258128 10774568960 1396713344 14.566 100 40 80 0 1447946192 40340400 10892448
21:03:56 22200 8192000000 10328637984 10766168064 1356904864 14.210 99 33 63 1 1417571968 50896832 9770272
21:06:30 79990 8192000000 10282277072 10737831936 1327432656 13.944 96 6 12 0 1355706528 27027072 1246800
21:08:51 49267 8192000000 10270423408 10713804800 1332617808 13.991 97 24 50 0 1343549232 6101472 4829952
21:10:46 89582 8192000000 10298278912 10736087040 1364105952 14.274 99 28 56 0 1383064048 12224640 6733456
21:13:20 137599 8192000000 10299343504 10739191808 1330064592 13.968 96 14 28 0 1348492688 15437680 2990416
21:16:44 27187 8192000000 10268683360 10711916544 1347997360 14.129 98 12 24 2 1356955760 6440080 2518320
21:18:19 12554 8192000000 10276931840 10724581376 1365367712 14.286 99 15 30 0 1379323600 10379152 3576736
21:19:34 24524 8192000000 10291766048 10740228096 1390693296 14.512 100 24 47 1 1402948560 7437552 4817712
21:21:00 7775 8192000000 10334698240 10762006528 1394742576 14.548 100 87 176 2 1430411616 16076800 19592240
21:22:08 36083 8192000000 10297276448 10763739136 1395581536 14.556 100 12 20 1 1406472816 8651792 2239488
21:24:48 53576 8192000000 10299855808 10771656704 1386414592 14.474 99 13 26 0 1401422720 12056112 2952016
21:26:52 38643 8192000000 10307261824 10770309120 1405945296 14.648 100 28 54 0 1419484720 7171632 6367792
21:28:32 30745 8192000000 10317603808 10779742208 1410935536 14.692 100 15 30 0 1426282528 11777680 3569312
21:30:04 65256 8192000000 10295367552 10760683520 1382249680 14.437 98 17 34 0 1399013744 12769168 3994896
21:32:10 56215 8192000000 10309688992 10777849856 1382256896 14.437 98 12 24 1 1408599216 23220464 3121856
21:34:10 78908 8192000000 10314777200 10774863872 1382559568 14.439 99 14 28 0 1401907168 15923904 3423696
21:36:29 33289 8192000000 10285252432 10732400640 1344421904 14.097 97 22 45 0 1356284400 7183184 4679312
21:38:05 40000 8192000000 10269250448 10708262912 1305734656 13.747 95 28 56 0 1320794704 9179616 5880432
21:39:52 104861 8192000000 10266331360 10716377088 1263754464 13.364 92 11 22 0 1274877744 9030992 2092288
21:42:39 96515 8192000000 10267728880 10711592960 1271239808 13.433 93 11 22 0 1283799936 10568544 1991584
21:45:23 130345 8192000000 10262890080 10725187584 1335992352 14.021 98 14 28 0 1357968400 18807568 3168480
21:48:34 66305 8192000000 10234420976 10698129408 1307629888 13.765 97 16 32 0 1324728512 13351760 3746864
21:50:41 61743 8192000000 10254717376 10700898304 1327691024 13.946 98 25 49 1 1345130128 11925728 5513376
21:52:55 58591 8192000000 10275339312 10740781056 1360970448 14.246 100 16 31 0 1384626928 19634752 4021728
21:55:05 33026 8192000000 10280763872 10722402304 1349717488 14.145 99 31 62 0 1374458448 17981072 6759888
21:56:43 36241 8192000000 10247939392 10692079616 1282591376 13.537 94 27 52 0 1299749184 10780048 6377760
21:58:23 28008 8192000000 10257834464 10701705216 1315923056 13.840 97 37 74 0 1332638416 8771792 7943568
21:59:56 27025 8192000000 10261694240 10697699328 1308882240 13.776 96 44 88 0 1330241744 11678352 9681152
22:01:25 68256 8192000000 10267946224 10738872320 1349934496 14.147 99 17 34 1 1372392928 18918592 3539840
22:03:36 103251 8192000000 10294366592 10738343936 1336469872 14.026 97 15 30 0 1353484816 14140752 2874192
22:06:19 84826 8192000000 10278961584 10737455104 1383467248 14.448 100 33 66 0 1400050544 8934944 7648352
22:08:48 89471 8192000000 10274464432 10740494336 1363160880 14.266 98 14 28 0 1381321344 15080864 3079600
22:11:18 31897 8192000000 10275049824 10747908096 1365880256 14.290 99 5 8 0 1380740544 13755120 1105168
22:12:52 73703 8192000000 10292709344 10753376256 1381971440 14.434 99 13 26 0 1392753168 7912288 2869440
22:15:09 19302 8192000000 10310394576 10764013568 1385541376 14.466 99 31 62 0 1401943760 9296688 7105696
22:17:23 25820 8192000000 10305475248 10754273280 1380407552 14.420 99 35 69 0 1400696928 12361072 7928304
22:18:59 64093 8192000000 10301894960 10755559424 1378377216 14.402 99 15 29 0 1394309184 12634560 3297408
22:21:04 62264 8192000000 10297890576 10748317696 1375212640 14.374 99 39 78 1 1395516048 11358112 8945296
22:23:08 68544 8192000000 10294677712 10747293696 1319643552 13.873 95 21 42 0 1341769088 16799600 5325936
22:25:17 103383 8192000000 10294165232 10744176640 1330885200 13.975 96 11 22 0 1346555904 13096512 2574192
22:28:03 98268 8192000000 10261431776 10716667904 1341383040 14.070 98 16 26 1 1352234816 8026448 2825328
22:30:42 23657 8192000000 10272922592 10728435712 1357224944 14.212 99 25 51 0 1374318688 11570800 5522944
22:32:05 45844 8192000000 10258547184 10719047680 1341971056 14.075 98 8 16 0 1349907920 6163200 1773664
22:33:54 79917 8192000000 10274238608 10736820224 1343707328 14.091 97 20 39 0 1364052144 15748496 4596320
22:36:59 65319 8192000000 10273945456 10749456384 1350330752 14.150 99 6 12 1 1366184768 14701040 1152976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2022 22:40:18 to 11-10-2022 22:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2022 22:40:34 to 11-10-2022 22:40:36.

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          243050
MON$OLDEST_ACTIVE               244366
MON$OLDEST_SNAPSHOT             244366
MON$NEXT_TRANSACTION            244366
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                       2026208
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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'

11.10.22 22:40:38. 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-11 22:40:40 No severe PSQL-related problems occured
11.10.22 22:40:41. End of report.