Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.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 15445152 kB
MemAvailable 30499268 kB
Buffers 0 kB
Cached 16256744 kB
SwapCached 2184 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-14 16:38:32
DTS_END                         2022-10-14 16:38:31.9460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40800
TRN_ID                          243661
ATT_ID                          644
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: 9616.98 18085 1154038
customer order: creation 182.84 306 21941
customer order: refuse 15.54 333 1865
order to supplier: creation 48.61 1276 5833
order to supplier: removal 3.48 99561 418
invoice (draft): creation 49.11 3122 5893
invoice (draft): removal 53.90 769 6468
invoice accept: apply 44.03 16819 5284
invoice accept: cancel 48.36 752 5803
customer reserve: creation 4112.94 165 493553
customer reserve: removal 4749.18 57 569901
realization accept: apply 87.71 180 10525
realization accept: cancel 85.04 116 10205
payment from customer: creation 62.43 119 7492
payment from customer: removal 5.72 79 686
payment to supplier: creation 51.82 145 6218
payment to supplier: removal 3.83 60 459
service: total inventory turnovers 4.66 1088 559
service: total monetary turnovers 6.88 29 825
service: aggregate perf. data 0.88 19686 106
service: refresh index statistics 0.03 217036 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-10-2022 16:39:23 to 14-10-2022 16:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3982
TEST_TIME 2 4498
TEST_TIME 3 3661
TEST_TIME 4 4602
TEST_TIME 5 4901
TEST_TIME 6 5050
TEST_TIME 7 5059
TEST_TIME 8 5204
TEST_TIME 9 5467
TEST_TIME 10 6054
TEST_TIME 11 6326
TEST_TIME 12 7136
TEST_TIME 13 7105
TEST_TIME 14 7195
TEST_TIME 15 7075
TEST_TIME 16 6999
TEST_TIME 17 7304
TEST_TIME 18 7503
TEST_TIME 19 8015
TEST_TIME 20 8102
TEST_TIME 21 8015
TEST_TIME 22 8530
TEST_TIME 23 8797
TEST_TIME 24 9214
TEST_TIME 25 9225
TEST_TIME 26 9591
TEST_TIME 27 9541
TEST_TIME 28 9637
TEST_TIME 29 9885
TEST_TIME 30 10965
TEST_TIME 31 10751
TEST_TIME 32 10797
TEST_TIME 33 10813
TEST_TIME 34 10806
TEST_TIME 35 10682
TEST_TIME 36 10600
TEST_TIME 37 10557
TEST_TIME 38 10609
TEST_TIME 39 10460
TEST_TIME 40 10282
TEST_TIME 41 10104
TEST_TIME 42 10259
TEST_TIME 43 10254
TEST_TIME 44 10135
TEST_TIME 45 10034
TEST_TIME 46 10009
TEST_TIME 47 9945
TEST_TIME 48 9961
TEST_TIME 49 10163
TEST_TIME 50 10072
TEST_TIME 51 10029
TEST_TIME 52 9984
TEST_TIME 53 9817
TEST_TIME 54 9902
TEST_TIME 55 9795
TEST_TIME 56 9772
TEST_TIME 57 9807
TEST_TIME 58 9818
TEST_TIME 59 9881
TEST_TIME 60 9864
TEST_TIME 61 9882
TEST_TIME 62 9846
TEST_TIME 63 9876
TEST_TIME 64 9863
TEST_TIME 65 9880
TEST_TIME 66 10082
TEST_TIME 67 10131
TEST_TIME 68 10097
TEST_TIME 69 10161
TEST_TIME 70 10183
TEST_TIME 71 10136
TEST_TIME 72 10031
TEST_TIME 73 10006
TEST_TIME 74 9997
TEST_TIME 75 9911
TEST_TIME 76 9897
TEST_TIME 77 9895
TEST_TIME 78 9814
TEST_TIME 79 9760
TEST_TIME 80 9730
TEST_TIME 81 9714
TEST_TIME 82 9764
TEST_TIME 83 9696
TEST_TIME 84 9713
TEST_TIME 85 9699
TEST_TIME 86 9702
TEST_TIME 87 9676
TEST_TIME 88 9670
TEST_TIME 89 9698
TEST_TIME 90 9760
TEST_TIME 91 9758
TEST_TIME 92 9776
TEST_TIME 93 9790
TEST_TIME 94 9795
TEST_TIME 95 9796
TEST_TIME 96 9886
TEST_TIME 97 9859
TEST_TIME 98 9869
TEST_TIME 99 9884
TEST_TIME 100 9796
TEST_TIME 101 9777
TEST_TIME 102 9764
TEST_TIME 103 9722
TEST_TIME 104 9745
TEST_TIME 105 9706
TEST_TIME 106 9684
TEST_TIME 107 9671
TEST_TIME 108 9644
TEST_TIME 109 9615
TEST_TIME 110 9592
TEST_TIME 111 9556
TEST_TIME 112 9564
TEST_TIME 113 9592
TEST_TIME 114 9591
TEST_TIME 115 9647
TEST_TIME 116 9634
TEST_TIME 117 9644
TEST_TIME 118 9620
TEST_TIME 119 9648
TEST_TIME 120 9617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2022 16:39:27 to 14-10-2022 16:39:30.

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 1610410 1610410 0 0.00 0 24675 6 0 0
doc_list_biud 1610410 1610410 0 0.00 0 83257 26 0 0
sp_add_invoice_to_stock 5438 5284 154 2.83 26 159401 16819 0 154
sp_cancel_adding_invoice 5803 5803 0 0.00 4 58783 753 0 0
sp_cancel_client_order 1865 1865 0 0.00 4 11028 333 0 0
sp_cancel_customer_reserve 569901 569901 0 0.00 0 47169 58 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 6940 80 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 2 1818 60 0 0
sp_cancel_supplier_invoice 6481 6468 13 0.20 1 83263 770 0 13
sp_cancel_supplier_order 418 418 0 0.00 6 1058986 99562 0 0
sp_cancel_write_off 10205 10205 0 0.00 1 23826 117 0 0
sp_client_order 21941 21941 0 0.00 3 27644 307 0 0
sp_customer_reserve 493553 493553 0 0.00 0 101949 165 0 0
sp_fill_shopping_cart 33654 33654 0 0.00 1 14832 37 0 0
sp_fill_shopping_cart_clo_res 493552 493552 0 0.00 0 15740 12 0 0
sp_get_clo_for_invoice 5283 5283 0 0.00 0 24027 277 0 0
sp_kill_cost_storno 17153 17153 0 0.00 0 7817 55 0 0
sp_kill_qstorno_ret_qs2qd 1156893 1156893 0 0.00 0 83253 31 0 0
sp_kill_qty_storno 610467 610467 0 0.00 0 83255 67 0 0
sp_lock_selected_doc 21906 21906 0 0.00 0 9338 6 0 0
sp_make_cost_storno 29517 29517 0 0.00 0 23245 58 0 0
sp_multiply_rows_for_pdistr 29517 29517 0 0.00 0 5545 24 0 0
sp_multiply_rows_for_qdistr 21941 21941 0 0.00 0 15700 148 0 0
sp_pay_from_customer 7492 7492 0 0.00 4 19712 120 0 0
sp_pay_to_supplier 6218 6218 0 0.00 4 19186 145 0 0
sp_payment_common 13710 13710 0 0.00 1 11297 26 0 0
sp_qd_handle_on_cancel_clo 1865 1865 0 0.00 0 11011 297 0 0
sp_qd_handle_on_invoice_upd_sts 11085 11085 0 0.00 0 22875 292 0 0
sp_qd_handle_on_reserve_upd_sts 10525 10525 0 0.00 0 19110 55 0 0
sp_reserve_write_off 10525 10525 0 0.00 6 21067 180 0 0
sp_supplier_invoice 5893 5893 0 0.00 5 120471 3122 0 0
sp_supplier_order 5833 5833 0 0.00 5 80696 1277 0 0
srv_aggregate_perf_data 106 106 0 0.00 2598 204918 19686 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1279 131608 46574 0 0
srv_find_qd_qs_mism 1103018 1103018 0 0.00 0 16928 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 37 88923 1088 0 0
srv_make_money_saldo 825 825 0 0.00 1 4337 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 147716 307875 217036 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 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 5284 5284 0 0.00 0 10687 144 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 3014 70 0 0
t$perf-norm:sp_cancel_client_order 1865 1865 0 0.00 0 2284 14 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 2034 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 2396 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 1637 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1820 18 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 24828 1603 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 5123 12 0 0
t$perf-norm:sp_client_order 21941 21941 0 0.00 0 9176 16 0 0
t$perf-norm:sp_customer_reserve 1108 1108 0 0.00 0 1560 6 0 0
t$perf-norm:sp_pay_from_customer 7492 7492 0 0.00 0 5151 9 0 0
t$perf-norm:sp_pay_to_supplier 6218 6218 0 0.00 0 3579 9 0 0
t$perf-norm:sp_reserve_write_off 10525 10525 0 0.00 0 7582 11 0 0
t$perf-norm:sp_supplier_invoice 5893 5893 0 0.00 0 11634 20 0 0
t$perf-norm:sp_supplier_order 5833 5833 0 0.00 0 6239 18 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1618 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 567 18 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 4290 32 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 595 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 7 0 0
v_add_invoice_to_stock 10872 10564 308 2.83 0 5058 21 0 308
v_all_customers 40650 40650 0 0.00 0 103 1 0 0
v_all_suppliers 32716 32716 0 0.00 0 22 1 0 0
v_all_wares 43880 43880 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2971 12 0 0
v_cancel_client_order 3730 3730 0 0.00 0 3796 8 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 4013 7 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 3614 11 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 1116 7 0 0
v_cancel_supplier_invoice 868 842 26 2.99 0 368 13 0 26
v_cancel_supplier_order 836 836 0 0.00 0 1626 5 0 0
v_cancel_write_off 2176 2176 0 0.00 0 3740 14 0 0
v_max_id_clo_ord 5832 5832 0 0.00 0 2600 2 0 0
v_max_id_clo_res 1108 1108 0 0.00 0 2734 18 0 0
v_max_id_ord_sup 5881 5881 0 0.00 0 9407 8 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 519 3 0 0
v_max_non_paid_realizn 3805 3805 0 0.00 0 3046 4 0 0
v_min_id_clo_ord 5832 5832 0 0.00 0 10341 9 0 0
v_min_id_clo_res 1108 1108 0 0.00 0 4479 13 0 0
v_min_id_ord_sup 5881 5881 0 0.00 0 14157 5 0 0
v_min_non_paid_invoice 6218 6218 0 0.00 0 9308 20 0 0
v_min_non_paid_realizn 7492 7492 0 0.00 0 8454 17 0 0
v_our_firm 10604 10604 0 0.00 0 18 1 0 0
v_reserve_write_off 21050 21050 0 0.00 0 5719 8 0 0
x_lock_depdocs_on_canc_invoice 11850 11850 0 0.00 0 22760 105 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 29336 443 0 0
x_make_qstorno_1000_1200 5833 5833 0 0.00 1 80682 1199 0 0
x_make_qstorno_1200_2000 5893 5893 0 0.00 1 120424 2988 0 0
x_make_qstorno_2100_3300 493553 493553 0 0.00 0 101943 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-10-2022 16:39:31 to 14-10-2022 16:39:36.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:37 34194 8192000000 9926724272 10240352256 1001491632 10.893 100 86 172 1 1030208288 6539024 22177632
14:11:13 15949 8192000000 9962722416 10286415872 1044398064 11.307 100 84 168 0 1073797600 8208640 21190896
14:12:42 12817 8192000000 9998316240 10332078080 1078089664 11.629 100 85 170 0 1109076880 10490400 20496816
14:14:04 9336 8192000000 10044479088 10388910080 1124830816 12.073 100 83 166 0 1156431216 12316800 19283600
14:15:16 7796 8192000000 10069987904 10424180736 1152813536 12.336 100 79 158 0 1184329072 13430144 18085392
14:16:28 2689 8192000000 10111584304 10476392448 1202333280 12.798 100 83 166 0 1234770704 13784352 18653072
14:17:32 1854 8192000000 10150287856 10521317376 1234778960 13.098 100 87 174 1 1266786304 12396736 19610608
14:18:35 2283 8192000000 10165438912 10544607232 1255309136 13.287 100 76 153 0 1286195984 13918688 16968160
14:19:39 1754 8192000000 10199408528 10582290432 1283067360 13.541 100 85 170 0 1314945088 12898720 18979008
14:20:44 15043 8192000000 10200902880 10590154752 1295512720 13.654 100 83 169 1 1328566992 14479408 18574864
14:22:02 4271 8192000000 10208022064 10602258432 1307018496 13.759 100 72 142 0 1332432576 8551648 16862432
14:23:07 6863 8192000000 10194367008 10606497792 1309408032 13.781 100 32 65 0 1323326288 6888160 7030096
14:24:17 7638 8192000000 10204674480 10618445824 1319814352 13.875 100 16 30 0 1328305488 5352592 3138544
14:25:28 11287 8192000000 10205534112 10624696320 1324005888 13.913 100 25 51 1 1336757568 7112864 5638816
14:26:41 30427 8192000000 10243702224 10646474752 1331548400 13.981 100 60 119 1 1352091936 6840064 13703472
14:28:13 7403 8192000000 10221824480 10638880768 1330291152 13.970 100 28 57 0 1346692848 10465392 5936304
14:29:23 10842 8192000000 10202278416 10612547584 1276490288 13.481 97 6 12 0 1282803552 5050784 1262480
14:30:34 49543 8192000000 10177150000 10590347264 1250334288 13.241 95 30 60 0 1262185184 4822816 7028080
14:32:27 6114 8192000000 10132136720 10532474880 1209018736 12.860 96 19 38 0 1218743424 5055168 4669520
14:33:36 9433 8192000000 10150408624 10547920896 1236592880 13.115 98 19 39 2 1246340352 5396768 4350704
14:34:52 66624 8192000000 10153571328 10560069632 1269911200 13.421 100 44 87 1 1285732656 5213088 10608368
14:36:59 6087 8192000000 10179186000 10588483584 1285744416 13.565 100 23 44 1 1296921264 5596864 5579984
14:38:07 13539 8192000000 10205614672 10617057280 1311146992 13.796 100 29 58 0 1322965328 5100288 6718048
14:39:29 9449 8192000000 10242935520 10648584192 1329850816 13.966 100 45 91 1 1351650784 12133232 9666736
14:40:47 51194 8192000000 10262438736 10676944896 1351175680 14.158 100 44 84 0 1374860896 12755168 10930048
14:42:42 35241 8192000000 10268279280 10698534912 1363049632 14.265 100 28 56 0 1386524144 16038208 7436304
14:44:24 16154 8192000000 10282451168 10706948096 1370897856 14.335 100 35 69 1 1392421712 13517680 8006176
14:45:51 7230 8192000000 10308046832 10726428672 1382215824 14.436 100 48 96 3 1412478944 19156704 11106416
14:47:02 2755 8192000000 10335944864 10750386176 1398941760 14.586 100 81 162 1 1443122352 25756480 18424112
14:48:07 5175 8192000000 10329053712 10753851392 1400308448 14.598 100 55 102 0 1438053584 24934384 12810752
14:49:15 6751 8192000000 10342833952 10762797056 1409231584 14.677 100 84 167 0 1457789344 28176800 20380960
14:50:28 3240 8192000000 10341007184 10763333632 1411828096 14.700 100 67 135 0 1457009792 28958240 16223456
14:51:39 2444 8192000000 10353183120 10772992000 1414061888 14.720 100 78 156 1 1456277952 22853312 19362752
14:52:52 2067 8192000000 10357817840 10784268288 1421089936 14.782 100 64 126 1 1466071680 28559504 16422240
14:54:02 1865 8192000000 10380668736 10801909760 1432488528 14.883 100 76 148 0 1483284160 32280832 18514800
14:55:09 3944 8192000000 10375845760 10795290624 1423432912 14.803 100 77 150 2 1482096432 40191200 18472320
14:56:17 1848 8192000000 10365383520 10784894976 1402894320 14.621 99 74 144 2 1461961808 40239376 18828112
14:57:24 9307 8192000000 10361046784 10792996864 1420208400 14.775 100 45 90 1 1467560032 35550016 11801616
14:58:49 5651 8192000000 10366150576 10790649856 1407528112 14.662 100 56 110 0 1468883840 47395808 13959920
15:00:01 3841 8192000000 10347501776 10778935296 1397322160 14.571 100 45 89 0 1461528880 51736912 12469808
15:01:13 1279 8192000000 10351542384 10764886016 1388141248 14.489 100 67 131 0 1450275296 45433552 16700496
15:02:16 7407 8192000000 10321711088 10734718976 1352701984 14.172 98 51 102 0 1410465424 44149248 13614192
15:03:30 14243 8192000000 10324146544 10752868352 1376944832 14.389 100 30 60 0 1429012688 43724240 8343616
15:05:19 29437 8192000000 10315459520 10753847296 1377987152 14.399 100 27 52 0 1422171456 37115248 7069056
15:07:14 14819 8192000000 10304176656 10744324096 1348440784 14.133 100 19 37 1 1405340688 50934272 5965632
15:10:10 73026 8192000000 10289156928 10742738944 1357308928 14.213 98 7 14 0 1368218224 9692512 1216784
15:12:25 67371 8192000000 10271622400 10717036544 1341275440 14.069 98 21 36 0 1354501392 9484624 3741328
15:14:39 39729 8192000000 10284600384 10717548544 1350072400 14.148 98 25 49 0 1366586512 11193984 5320128
15:16:20 61983 8192000000 10282768368 10722160640 1335139120 14.014 97 33 66 0 1356740864 13933184 7668560
15:18:23 124062 8192000000 10268882848 10727112704 1373856384 14.362 100 31 62 0 1390631312 9334688 7440240
15:21:31 123771 8192000000 10330119392 10763411456 1392480288 14.528 100 47 94 0 1414648544 11277200 10891056
15:24:37 64990 8192000000 10310724064 10763603968 1380933696 14.425 98 20 41 0 1402835568 16953120 4948752
15:26:47 32548 8192000000 10300112480 10773651456 1381662432 14.431 99 22 44 1 1412412960 24827808 5922720
15:28:21 55140 8192000000 10297315584 10759516160 1398354960 14.580 100 16 31 0 1412702848 10613856 3734032
15:31:12 49400 8192000000 10313127168 10781990912 1411188128 14.694 100 12 24 0 1428389504 14313376 2888000
15:33:02 110316 8192000000 10315004176 10782232576 1398306336 14.580 99 12 23 0 1421570960 20768064 2496560
15:36:01 55829 8192000000 10307408112 10776481792 1390745472 14.513 99 18 36 0 1408366976 13473776 4147728
15:38:04 62877 8192000000 10297517008 10754412544 1358376800 14.223 97 30 59 1 1375338752 10014896 6947056
15:40:11 59270 8192000000 10267185552 10713018368 1297596880 13.673 94 26 52 1 1313983424 10748896 5637648
15:42:13 29209 8192000000 10282708448 10724945920 1340991600 14.066 98 27 54 0 1360672880 13570992 6110288
15:43:49 85636 8192000000 10252698160 10724089856 1277131744 13.487 94 8 16 1 1316213344 36836176 2245424
15:47:01 42663 8192000000 10270207552 10705752064 1322708096 13.901 98 28 56 0 1345208064 16223632 6276336
15:48:50 63600 8192000000 10276780912 10714755072 1330969936 13.976 98 19 38 0 1349909152 14943712 3995504
15:50:56 84820 8192000000 10265386832 10708561920 1334102352 14.004 98 32 64 0 1349188784 8108384 6978048
15:53:31 27137 8192000000 10250347664 10696957952 1318988432 13.868 97 16 32 0 1331762768 9429776 3344560
15:54:58 78171 8192000000 10238652528 10692214784 1314973616 13.831 97 9 18 0 1325793552 8492112 2327824
15:57:17 61569 8192000000 10267180496 10724388864 1354747776 14.190 99 14 28 0 1368171888 10244592 3179520
15:59:19 54865 8192000000 10259475408 10713890816 1341387248 14.070 98 5 10 0 1353024224 10369776 1267200
16:01:18 91819 8192000000 10252906736 10718793728 1319818224 13.875 97 6 12 0 1333661264 12626592 1216448
16:03:53 60290 8192000000 10263898736 10719023104 1326871296 13.939 97 8 16 0 1338554928 9993056 1690576
16:05:54 36084 8192000000 10291113072 10734469120 1374220000 14.365 100 37 72 0 1393382224 10726480 8435744
16:07:34 36960 8192000000 10291203728 10738315264 1374521600 14.368 100 23 46 0 1393255712 13690416 5043696
16:09:12 131608 8192000000 10295170704 10765586432 1344378368 14.097 97 13 26 0 1364584768 17634272 2572128
16:12:28 67035 8192000000 10302664224 10753630208 1354591088 14.189 97 12 24 0 1368656384 11287536 2777760
16:14:35 95260 8192000000 10303517776 10758057984 1385351888 14.464 99 23 45 0 1401895904 11813184 4730832
16:17:21 91050 8192000000 10293207408 10739568640 1360105008 14.238 97 13 26 0 1373856048 10956528 2794512
16:19:56 77926 8192000000 10283291664 10742034432 1363263408 14.267 97 21 38 1 1379770048 12285472 4221168
16:22:33 50395 8192000000 10274879184 10739097600 1375568064 14.377 100 13 26 1 1387895536 9447280 2880192
16:24:33 60804 8192000000 10263700192 10727317504 1354950912 14.192 98 17 34 1 1367637568 8477632 4209024
16:26:52 46347 8192000000 10255724720 10720624640 1345171392 14.104 98 14 28 0 1357605072 9043872 3389808
16:28:52 118915 8192000000 10278966352 10750771200 1337794224 14.038 97 3 6 0 1348470320 10069152 606944
16:32:00 44333 8192000000 10273439504 10727313408 1362782384 14.262 99 16 32 2 1378472880 11903440 3787056
16:34:01 52589 8192000000 10268357360 10718302208 1330954928 13.976 97 19 38 0 1344603232 8673440 4974864
16:36:15 25240 8192000000 10276931520 10714406912 1348987792 14.138 99 38 76 0 1366722944 9562688 8172464
16:37:42 57480 8192000000 9345295984 9641230336 459762512 5.314 34 8 16 0 465876768 4522576 1591680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2022 16:39:38 to 14-10-2022 16:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 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 14-10-2022 16:39:54 to 14-10-2022 16:39:56.

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          243440
MON$OLDEST_ACTIVE               244768
MON$OLDEST_SNAPSHOT             244768
MON$NEXT_TRANSACTION            244768
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                       2033184
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 ---
:330: 
:331: mscs-test-161.budzdorov.ru    Fri Oct 14 16:38:45 2022
:332:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:333: 
:334: 
:335: mscs-test-161.budzdorov.ru    Fri Oct 14 16:38:47 2022
:336:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:337: 
:338: 
:339: mscs-test-161.budzdorov.ru    Fri Oct 14 16:38:47 2022
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
--- end of diff output ---

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

14.10.22 16:39:57. 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-14 16:39:59 No severe PSQL-related problems occured
14.10.22 16:40:01. End of report.