Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2022 01: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 16814156 kB
MemAvailable 31522300 kB
Buffers 0 kB
Cached 14869692 kB
SwapCached 2280 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.10
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-04-07 01:38:12
DTS_END                         2022-04-07 01:38:12.9610
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/49586
TRN_ID                          242175
ATT_ID                          698
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: 8971.35 21730 1076562
customer order: creation 173.03 360 20764
customer order: refuse 15.09 330 1811
order to supplier: creation 44.93 1325 5392
order to supplier: removal 3.44 95523 413
invoice (draft): creation 45.53 3216 5463
invoice (draft): removal 50.80 858 6096
invoice accept: apply 41.55 17052 4986
invoice accept: cancel 45.71 810 5485
customer reserve: creation 3789.26 170 454711
customer reserve: removal 4468.80 56 536256
realization accept: apply 83.69 197 10043
realization accept: cancel 79.52 117 9542
payment from customer: creation 58.85 164 7062
payment from customer: removal 5.58 205 670
payment to supplier: creation 49.16 184 5899
payment to supplier: removal 3.75 67 450
service: total inventory turnovers 4.98 949 598
service: total monetary turnovers 6.72 21 806
service: aggregate perf. data 0.93 17224 111
service: refresh index statistics 0.03 295779 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-04-2022 01:39:24 to 07-04-2022 01:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3989
TEST_TIME 2 4087
TEST_TIME 3 3777
TEST_TIME 4 3663
TEST_TIME 5 3736
TEST_TIME 6 3992
TEST_TIME 7 4848
TEST_TIME 8 5126
TEST_TIME 9 5079
TEST_TIME 10 5610
TEST_TIME 11 6180
TEST_TIME 12 6674
TEST_TIME 13 6573
TEST_TIME 14 7276
TEST_TIME 15 7357
TEST_TIME 16 7273
TEST_TIME 17 7228
TEST_TIME 18 7315
TEST_TIME 19 7533
TEST_TIME 20 8285
TEST_TIME 21 8182
TEST_TIME 22 8288
TEST_TIME 23 8725
TEST_TIME 24 9280
TEST_TIME 25 9871
TEST_TIME 26 9825
TEST_TIME 27 9919
TEST_TIME 28 10571
TEST_TIME 29 10330
TEST_TIME 30 10184
TEST_TIME 31 9961
TEST_TIME 32 10068
TEST_TIME 33 10063
TEST_TIME 34 9883
TEST_TIME 35 9748
TEST_TIME 36 9609
TEST_TIME 37 9561
TEST_TIME 38 9555
TEST_TIME 39 9588
TEST_TIME 40 9377
TEST_TIME 41 9306
TEST_TIME 42 9200
TEST_TIME 43 9259
TEST_TIME 44 9244
TEST_TIME 45 9133
TEST_TIME 46 8964
TEST_TIME 47 8984
TEST_TIME 48 8907
TEST_TIME 49 8948
TEST_TIME 50 8991
TEST_TIME 51 8939
TEST_TIME 52 9141
TEST_TIME 53 9148
TEST_TIME 54 9060
TEST_TIME 55 9217
TEST_TIME 56 9149
TEST_TIME 57 9188
TEST_TIME 58 9319
TEST_TIME 59 9347
TEST_TIME 60 9392
TEST_TIME 61 9420
TEST_TIME 62 9357
TEST_TIME 63 9264
TEST_TIME 64 9197
TEST_TIME 65 9140
TEST_TIME 66 9028
TEST_TIME 67 9037
TEST_TIME 68 8964
TEST_TIME 69 8924
TEST_TIME 70 8939
TEST_TIME 71 8965
TEST_TIME 72 8969
TEST_TIME 73 8936
TEST_TIME 74 8954
TEST_TIME 75 9062
TEST_TIME 76 9039
TEST_TIME 77 9099
TEST_TIME 78 9084
TEST_TIME 79 9133
TEST_TIME 80 9124
TEST_TIME 81 9165
TEST_TIME 82 9131
TEST_TIME 83 9196
TEST_TIME 84 9096
TEST_TIME 85 9066
TEST_TIME 86 9061
TEST_TIME 87 9110
TEST_TIME 88 9091
TEST_TIME 89 9071
TEST_TIME 90 9053
TEST_TIME 91 9051
TEST_TIME 92 9057
TEST_TIME 93 9045
TEST_TIME 94 9039
TEST_TIME 95 9146
TEST_TIME 96 9124
TEST_TIME 97 9208
TEST_TIME 98 9158
TEST_TIME 99 9136
TEST_TIME 100 9231
TEST_TIME 101 9225
TEST_TIME 102 9207
TEST_TIME 103 9201
TEST_TIME 104 9238
TEST_TIME 105 9309
TEST_TIME 106 9261
TEST_TIME 107 9181
TEST_TIME 108 9135
TEST_TIME 109 9123
TEST_TIME 110 9134
TEST_TIME 111 9103
TEST_TIME 112 9059
TEST_TIME 113 9033
TEST_TIME 114 8988
TEST_TIME 115 9036
TEST_TIME 116 9053
TEST_TIME 117 9035
TEST_TIME 118 9057
TEST_TIME 119 9032
TEST_TIME 120 8975

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-04-2022 01:39:28 to 07-04-2022 01:39:31.

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 1496312 1496312 0 0.00 0 34904 7 0 0
doc_list_biud 1496312 1496312 0 0.00 0 35876 26 0 0
sp_add_invoice_to_stock 5110 4986 124 2.42 29 199004 17053 0 124
sp_cancel_adding_invoice 5485 5485 0 0.00 4 64410 811 0 0
sp_cancel_client_order 1811 1811 0 0.00 4 9693 331 0 0
sp_cancel_customer_reserve 536256 536256 0 0.00 1 35625 57 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 30070 206 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 2623 67 0 0
sp_cancel_supplier_invoice 6111 6096 15 0.24 1 17721 859 0 15
sp_cancel_supplier_order 413 413 0 0.00 5 988178 95523 0 0
sp_cancel_write_off 9542 9542 0 0.00 1 21645 118 0 0
sp_client_order 20764 20764 0 0.00 3 30986 360 0 0
sp_customer_reserve 454711 454711 0 0.00 0 47377 171 0 0
sp_fill_shopping_cart 31615 31615 0 0.00 1 18178 42 0 0
sp_fill_shopping_cart_clo_res 454707 454707 0 0.00 0 13439 14 0 0
sp_get_clo_for_invoice 4981 4981 0 0.00 0 20009 314 0 0
sp_kill_cost_storno 16147 16147 0 0.00 0 30062 65 0 0
sp_kill_qstorno_ret_qs2qd 1088563 1088563 0 0.00 0 35601 31 0 0
sp_kill_qty_storno 574627 574627 0 0.00 0 35875 68 0 0
sp_lock_selected_doc 20814 20814 0 0.00 0 7985 5 0 0
sp_make_cost_storno 27985 27985 0 0.00 0 34901 62 0 0
sp_multiply_rows_for_pdistr 27985 27985 0 0.00 0 30739 39 0 0
sp_multiply_rows_for_qdistr 20764 20764 0 0.00 0 12113 183 0 0
sp_pay_from_customer 7062 7062 0 0.00 4 30668 165 0 0
sp_pay_to_supplier 5899 5899 0 0.00 4 31372 185 0 0
sp_payment_common 12961 12961 0 0.00 1 21871 57 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 0 9678 296 0 0
sp_qd_handle_on_invoice_upd_sts 10466 10466 0 0.00 0 35873 340 0 0
sp_qd_handle_on_reserve_upd_sts 10043 10043 0 0.00 0 16601 51 0 0
sp_reserve_write_off 10043 10043 0 0.00 6 30812 198 0 0
sp_supplier_invoice 5463 5463 0 0.00 6 88235 3216 0 0
sp_supplier_order 5392 5392 0 0.00 5 70752 1326 0 0
srv_aggregate_perf_data 111 111 0 0.00 2443 207702 17224 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1599 131622 45872 0 0
srv_find_qd_qs_mism 1028432 1028432 0 0.00 0 16681 6 0 0
srv_make_invnt_saldo 598 598 0 0.00 48 41086 950 0 0
srv_make_money_saldo 806 806 0 0.00 0 1191 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 190640 419153 295779 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 35662 186 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 6461 100 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 7397 18 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 629 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4246 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 8525 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 840 14 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 24102 1565 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2750 7 0 0
t$perf-norm:sp_client_order 20764 20764 0 0.00 0 34171 19 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 9854 20 0 0
t$perf-norm:sp_pay_from_customer 7062 7062 0 0.00 0 7504 12 0 0
t$perf-norm:sp_pay_to_supplier 5899 5899 0 0.00 0 7982 12 0 0
t$perf-norm:sp_reserve_write_off 10043 10043 0 0.00 0 8568 15 0 0
t$perf-norm:sp_supplier_invoice 5463 5463 0 0.00 0 14024 27 0 0
t$perf-norm:sp_supplier_order 5392 5392 0 0.00 0 15248 21 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 217 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 104 2 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 5328 26 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 17363 39 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10210 9962 248 2.42 0 10151 21 0 248
v_all_customers 37812 37812 0 0.00 0 15 0 0 0
v_all_suppliers 30068 30068 0 0.00 0 80 1 0 0
v_all_wares 41528 41528 0 0.00 0 114 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 4460 17 0 0
v_cancel_client_order 3622 3622 0 0.00 0 8629 11 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 12073 25 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 5045 15 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 2601 6 0 0
v_cancel_supplier_invoice 754 724 30 3.97 0 2608 17 0 30
v_cancel_supplier_order 826 826 0 0.00 0 4553 11 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2270 11 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 1328 1 0 0
v_max_id_clo_res 997 997 0 0.00 0 2904 11 0 0
v_max_id_ord_sup 5459 5459 0 0.00 0 3979 3 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 168 2 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 18677 11 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 5204 11 0 0
v_min_id_clo_res 997 997 0 0.00 0 2181 4 0 0
v_min_id_ord_sup 5459 5459 0 0.00 0 3350 4 0 0
v_min_non_paid_invoice 5899 5899 0 0.00 0 21861 40 0 0
v_min_non_paid_realizn 7062 7062 0 0.00 0 21841 55 0 0
v_our_firm 10364 10364 0 0.00 0 18 1 0 0
v_reserve_write_off 20086 20086 0 0.00 0 9838 11 0 0
x_lock_depdocs_on_canc_invoice 11219 11219 0 0.00 0 16790 130 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 7756 337 0 0
x_make_qstorno_1000_1200 5392 5392 0 0.00 1 68404 1248 0 0
x_make_qstorno_1200_2000 5463 5463 0 0.00 3 87955 3059 0 0
x_make_qstorno_2100_3300 454711 454711 0 0.00 0 47375 154 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-04-2022 01:39:32 to 07-04-2022 01:39:37.

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
23:08:16 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:18 17385 8192000000 9882633472 10188664832 968275808 10.570 100 85 170 0 996637712 5408224 22953680
23:10:41 13007 8192000000 9932932560 10249125888 1016552576 11.039 100 89 178 1 1046972480 7573840 22846064
23:11:59 10703 8192000000 9957648944 10283548672 1044558608 11.308 100 81 162 0 1074025328 8901872 20564848
23:13:18 8946 8192000000 10022052864 10366418944 1109134384 11.924 100 82 164 0 1138808192 10071120 19602688
23:14:34 13072 8192000000 10066428912 10425286656 1154740704 12.354 100 75 149 0 1183406336 11328928 17336704
23:15:53 11706 8192000000 10122213504 10489004032 1211398448 12.882 100 78 157 2 1240544112 11526096 17619568
23:17:09 3651 8192000000 10163884304 10536910848 1246918800 13.210 100 93 186 0 1282247184 14536912 20791472
23:18:14 2559 8192000000 10179995280 10559283200 1270564928 13.427 100 81 162 0 1300134032 12130576 17438528
23:19:18 999 8192000000 10215411296 10598404096 1293968992 13.640 100 90 176 1 1327730736 13435056 20326688
23:20:19 9011 8192000000 10197248576 10602672128 1304599392 13.737 100 40 81 5 1319612480 5571376 9441712
23:21:31 13350 8192000000 10198379200 10615468032 1314058192 13.823 100 18 37 1 1324111600 6243248 3810160
23:22:48 11129 8192000000 10204164656 10624954368 1324245120 13.915 100 14 28 1 1333167200 5634672 3287408
23:24:02 67881 8192000000 10227475968 10654478336 1338623568 14.045 100 28 56 0 1350346768 5496000 6227200
23:26:17 7614 8192000000 10234876976 10654633984 1338118112 14.040 100 30 60 0 1352027872 7393024 6516736
23:27:26 6543 8192000000 10218977808 10634878976 1310756320 13.793 99 26 52 0 1322040992 5051536 6233136
23:28:36 40141 8192000000 10142197568 10543591424 1162958768 12.431 88 29 58 0 1175179952 5023088 7198096
23:30:37 4270 8192000000 10141601840 10538455040 1230504064 13.059 98 26 52 3 1241945680 5552912 5888704
23:31:42 7287 8192000000 10125481424 10525634560 1189787904 12.681 95 22 44 1 1200997040 4989088 6220048
23:32:51 12496 8192000000 10167889040 10576855040 1274636640 13.464 100 30 60 0 1287135584 5169424 7329520
23:34:03 57197 8192000000 10194725664 10607001600 1295190832 13.651 100 23 46 2 1309939808 9963600 4785376
23:36:04 6394 8192000000 10204423472 10618212352 1311577392 13.800 100 44 88 0 1327987360 6208496 10201472
23:37:11 4233 8192000000 10213133232 10628423680 1320482096 13.881 100 37 74 1 1335723952 6099376 9142480
23:38:22 32108 8192000000 10235540688 10652463104 1340592912 14.063 100 23 45 0 1350508544 4463152 5452480
23:40:00 74738 8192000000 10257542064 10683236352 1356417312 14.205 100 28 55 2 1372650208 8588112 7644784
23:42:37 15164 8192000000 10290440912 10709495808 1378596544 14.404 100 45 90 1 1401444384 12264224 10583616
23:43:55 14954 8192000000 10312309312 10726559744 1387074128 14.480 100 57 114 0 1418196640 17960400 13162112
23:45:20 29808 8192000000 10316216048 10740273152 1391488704 14.519 100 48 96 4 1424363824 21559440 11315680
23:47:01 15052 8192000000 10335114464 10756247552 1406497936 14.653 100 54 108 0 1443254384 23841568 12914880
23:48:23 18678 8192000000 10326437200 10754838528 1406379376 14.652 100 52 104 1 1443458464 24033600 13045488
23:49:46 3095 8192000000 10348940784 10773938176 1414228096 14.721 100 64 127 0 1458185280 29038752 14918432
23:50:53 1597 8192000000 10334653488 10749460480 1377578400 14.395 98 75 148 1 1426864528 31893264 17392864
23:52:01 7269 8192000000 10326503472 10742607872 1380785856 14.424 100 54 108 2 1431354224 36900208 13668160
23:53:14 2020 8192000000 10333983024 10742484992 1387618272 14.485 100 70 138 0 1436705200 32751984 16334944
23:54:25 5191 8192000000 10333237968 10744172544 1379472896 14.412 99 71 140 0 1430150992 32568816 18109280
23:55:40 4333 8192000000 10347026624 10755055616 1390628176 14.511 100 75 150 0 1449755712 39512320 19615216
23:56:50 9841 8192000000 10340670512 10756886528 1386172960 14.472 100 56 112 0 1445874576 44172864 15528752
23:58:09 12640 8192000000 10319761344 10740883456 1366018864 14.291 99 47 94 4 1423651456 44733680 12898912
23:59:34 25312 8192000000 10309579280 10736230400 1356192272 14.203 99 36 73 1 1410682768 44385232 10105264
00:01:08 4910 8192000000 10329012384 10749956096 1361770128 14.253 100 56 112 0 1428696048 51586784 15339136
00:02:15 21939 8192000000 10274331120 10708262912 1321739248 13.892 99 23 45 0 1376019072 47885776 6394048
00:03:38 44602 8192000000 10265738576 10697641984 1319109424 13.869 98 12 24 0 1350621472 28417040 3095008
00:05:26 34009 8192000000 10279515040 10724982784 1349108800 14.139 100 25 50 2 1391559440 35062704 7387936
00:07:01 59244 8192000000 10274166560 10717130752 1353180336 14.176 99 26 51 1 1370927296 11763696 5983264
00:09:01 33681 8192000000 10286222032 10724638720 1373273584 14.356 100 21 42 0 1390090192 12101728 4714880
00:10:39 50124 8192000000 10276719104 10716024832 1345039648 14.103 98 16 33 0 1359870208 10807312 4023248
00:12:30 44489 8192000000 10281014816 10733404160 1381525920 14.430 100 11 22 0 1393618144 9864400 2227824
00:14:16 51977 8192000000 10293240368 10747043840 1378077152 14.399 99 12 24 1 1398508192 17554688 2876352
00:16:17 97310 8192000000 10304980928 10765545472 1394153776 14.543 100 18 35 0 1418613344 19810304 4649264
00:20:00 22620 8192000000 10304811696 10765295616 1404132144 14.632 100 34 68 3 1424062016 11928208 8001664
00:21:27 33432 8192000000 10294018704 10747027456 1381130816 14.427 98 10 20 0 1389788704 6128240 2529648
00:23:58 70406 8192000000 10283040064 10744356864 1386771744 14.477 99 17 34 1 1397935392 7350064 3813584
00:27:38 51209 8192000000 10314003648 10777354240 1409957936 14.684 100 18 34 1 1428040784 13621104 4461744
00:29:30 47740 8192000000 10308175936 10778611712 1408657072 14.672 100 14 28 0 1426780128 15070560 3052496
00:31:27 47311 8192000000 10310194208 10758733824 1347969728 14.129 95 20 40 0 1359768784 7655600 4143456
00:33:15 44070 8192000000 10305550336 10774822912 1408080992 14.667 100 16 32 1 1427437824 14774848 4581984
00:35:11 56290 8192000000 10324029040 10781335552 1385255168 14.464 98 21 42 0 1404035040 13486880 5292992
00:37:11 131622 8192000000 10290568544 10741067776 1305511392 13.745 93 9 18 0 1334260896 26741616 2007888
00:40:24 121369 8192000000 10288461040 10736254976 1349604032 14.144 97 28 56 1 1363193280 6793536 6795712
00:43:29 62529 8192000000 10272725008 10730512384 1366050368 14.292 99 9 18 0 1378296992 9602608 2644016
00:45:40 12705 8192000000 10267406080 10739056640 1375149600 14.373 100 5 10 0 1384522640 8696144 676896
00:46:53 49723 8192000000 10270436272 10725687296 1323619824 13.909 96 13 26 0 1332175648 5758080 2797744
00:48:46 111198 8192000000 10265998064 10720796672 1285852432 13.566 93 11 22 0 1297718224 9515728 2350064
00:51:39 66060 8192000000 10253450560 10699587584 1305534032 13.746 96 31 61 3 1327684944 14922896 7228016
00:53:46 22685 8192000000 10236169472 10662199296 1260338256 13.333 93 20 40 1 1271107456 6283328 4485872
00:55:09 23883 8192000000 10250647680 10686275584 1328930128 13.957 100 37 74 0 1353925296 16606752 8388416
00:56:33 53831 8192000000 10258573664 10694901760 1317822176 13.857 98 27 54 0 1334637088 10809072 6005840
00:58:29 70791 8192000000 10260228672 10709098496 1347282016 14.123 100 23 46 1 1364948096 12821184 4844896
01:00:48 71983 8192000000 10268270624 10728398848 1336264848 14.024 98 23 47 3 1355645008 13836752 5543408
01:03:01 79002 8192000000 10279714592 10724839424 1356488208 14.206 99 20 40 0 1371747664 10467520 4791936
01:05:37 71116 8192000000 10280850720 10754699264 1376974544 14.389 100 7 13 2 1399544992 20991840 1578608
01:07:48 65427 8192000000 10280194688 10736910336 1358055488 14.220 98 10 20 1 1377212416 16824064 2332864
01:10:14 78530 8192000000 10302575456 10753224704 1378829072 14.406 99 18 37 1 1388574688 5811424 3934192
01:12:34 64829 8192000000 10293830080 10766635008 1340030464 14.058 96 15 30 0 1362449664 18610320 3808880
01:14:41 45315 8192000000 10299938800 10751250432 1380834672 14.424 99 22 44 2 1398621840 13179408 4607760
01:16:29 25729 8192000000 10291572656 10732253184 1352489328 14.170 97 20 40 3 1364142800 7442800 4210672
01:17:58 48028 8192000000 10291311472 10743795712 1346801536 14.119 97 27 54 3 1368793248 15666848 6324864
01:19:57 61798 8192000000 10297786736 10747437056 1367290816 14.303 99 26 52 1 1382745728 9865920 5588992
01:21:59 32477 8192000000 10310334272 10758303744 1375224432 14.374 99 23 46 0 1391146384 10683488 5238464
01:23:34 73994 8192000000 10296032608 10751885312 1350753248 14.154 97 18 36 0 1369653616 14649088 4251280
01:26:49 52643 8192000000 10284551280 10747457536 1378466224 14.403 99 13 26 0 1392663696 11624160 2573312
01:28:42 74199 8192000000 10282241504 10744885248 1354973936 14.192 97 10 20 0 1369628416 12337888 2316592
01:30:56 104458 8192000000 10293184896 10749231104 1349988016 14.147 97 13 26 0 1368457616 14547936 3921664
01:34:51 51389 8192000000 10279783424 10731753472 1348806352 14.137 97 17 34 0 1361892704 8868592 4217760
01:36:48 29520 8192000000 10269890800 10707574784 1299188016 13.688 95 33 66 1 1313871152 7351712 7331424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-04-2022 01:39:38 to 07-04-2022 01:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2022 01:39:54 to 07-04-2022 01: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          240637
MON$OLDEST_ACTIVE               243825
MON$OLDEST_SNAPSHOT             243825
MON$NEXT_TRANSACTION            243825
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                       2009024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33576 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33576 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33576 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33576 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Apr  7 01:38:14 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Apr  7 01:38:18 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Apr  7 01:38:20 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Thu Apr  7 01:38:23 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
--- end of diff output ---

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

07.04.22 01: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-04-07 01:39:59 No severe PSQL-related problems occured
07.04.22 01:40:01. End of report.