Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2022 19:38
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 16467088 kB
MemAvailable 31298048 kB
Buffers 0 kB
Cached 15175740 kB
SwapCached 1196 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.9
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-01-02 19:38:02
DTS_END                         2022-01-02 19:38:02.3350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37234
TRN_ID                          237195
ATT_ID                          659
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: 9177.40 21841 1101288
customer order: creation 169.26 371 20311
customer order: refuse 14.71 337 1765
order to supplier: creation 44.49 1246 5339
order to supplier: removal 3.32 109862 398
invoice (draft): creation 45.97 3320 5516
invoice (draft): removal 52.72 835 6326
invoice accept: apply 41.13 18745 4936
invoice accept: cancel 47.35 770 5682
customer reserve: creation 3802.73 184 456328
customer reserve: removal 4666.72 61 560006
realization accept: apply 81.10 184 9732
realization accept: cancel 81.09 107 9731
payment from customer: creation 57.49 110 6899
payment from customer: removal 5.40 90 648
payment to supplier: creation 48.49 130 5819
payment to supplier: removal 3.58 96 430
service: total inventory turnovers 4.68 1166 562
service: total monetary turnovers 6.32 25 758
service: aggregate perf. data 0.82 22568 98
service: refresh index statistics 0.03 276616 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2022 19:38:50 to 02-01-2022 19:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8125
TEST_TIME 2 6430
TEST_TIME 3 5984
TEST_TIME 4 6032
TEST_TIME 5 5912
TEST_TIME 6 5883
TEST_TIME 7 5973
TEST_TIME 8 5923
TEST_TIME 9 6108
TEST_TIME 10 6696
TEST_TIME 11 6548
TEST_TIME 12 6581
TEST_TIME 13 6852
TEST_TIME 14 7608
TEST_TIME 15 7743
TEST_TIME 16 7917
TEST_TIME 17 8510
TEST_TIME 18 8484
TEST_TIME 19 8604
TEST_TIME 20 8767
TEST_TIME 21 8682
TEST_TIME 22 8731
TEST_TIME 23 8759
TEST_TIME 24 8878
TEST_TIME 25 8965
TEST_TIME 26 9282
TEST_TIME 27 9568
TEST_TIME 28 10397
TEST_TIME 29 11117
TEST_TIME 30 11226
TEST_TIME 31 11077
TEST_TIME 32 10816
TEST_TIME 33 10672
TEST_TIME 34 10488
TEST_TIME 35 10353
TEST_TIME 36 10230
TEST_TIME 37 10099
TEST_TIME 38 10031
TEST_TIME 39 9960
TEST_TIME 40 10023
TEST_TIME 41 9933
TEST_TIME 42 9787
TEST_TIME 43 9687
TEST_TIME 44 9722
TEST_TIME 45 9673
TEST_TIME 46 9610
TEST_TIME 47 9506
TEST_TIME 48 9372
TEST_TIME 49 9284
TEST_TIME 50 9203
TEST_TIME 51 9362
TEST_TIME 52 9246
TEST_TIME 53 9274
TEST_TIME 54 9270
TEST_TIME 55 9179
TEST_TIME 56 9074
TEST_TIME 57 9263
TEST_TIME 58 9140
TEST_TIME 59 9167
TEST_TIME 60 9150
TEST_TIME 61 9137
TEST_TIME 62 9175
TEST_TIME 63 9256
TEST_TIME 64 9176
TEST_TIME 65 9201
TEST_TIME 66 9255
TEST_TIME 67 9218
TEST_TIME 68 9330
TEST_TIME 69 9271
TEST_TIME 70 9295
TEST_TIME 71 9295
TEST_TIME 72 9301
TEST_TIME 73 9284
TEST_TIME 74 9247
TEST_TIME 75 9160
TEST_TIME 76 9172
TEST_TIME 77 9135
TEST_TIME 78 9148
TEST_TIME 79 9107
TEST_TIME 80 9131
TEST_TIME 81 9085
TEST_TIME 82 9112
TEST_TIME 83 9110
TEST_TIME 84 9060
TEST_TIME 85 9065
TEST_TIME 86 9036
TEST_TIME 87 9038
TEST_TIME 88 9038
TEST_TIME 89 9017
TEST_TIME 90 9021
TEST_TIME 91 9102
TEST_TIME 92 9051
TEST_TIME 93 9129
TEST_TIME 94 9152
TEST_TIME 95 9200
TEST_TIME 96 9210
TEST_TIME 97 9233
TEST_TIME 98 9285
TEST_TIME 99 9273
TEST_TIME 100 9327
TEST_TIME 101 9402
TEST_TIME 102 9340
TEST_TIME 103 9260
TEST_TIME 104 9233
TEST_TIME 105 9241
TEST_TIME 106 9192
TEST_TIME 107 9142
TEST_TIME 108 9093
TEST_TIME 109 9155
TEST_TIME 110 9126
TEST_TIME 111 9114
TEST_TIME 112 9159
TEST_TIME 113 9212
TEST_TIME 114 9185
TEST_TIME 115 9197
TEST_TIME 116 9200
TEST_TIME 117 9203
TEST_TIME 118 9227
TEST_TIME 119 9258
TEST_TIME 120 9183

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2022 19:38:53 to 02-01-2022 19:38:57.

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 1522873 1522873 0 0.00 0 17082 6 0 0
doc_list_biud 1522871 1522871 0 0.00 0 28176 29 0 0
sp_add_invoice_to_stock 5034 4936 98 1.94 21 174086 18746 0 98
sp_cancel_adding_invoice 5682 5682 0 0.00 5 65993 771 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 8352 337 0 0
sp_cancel_customer_reserve 560006 560006 0 0.00 1 16068 61 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 6323 91 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 9919 97 0 0
sp_cancel_supplier_invoice 6339 6326 13 0.20 2 15768 835 0 13
sp_cancel_supplier_order 398 398 0 0.00 8 916528 109863 0 0
sp_cancel_write_off 9731 9731 0 0.00 1 12732 107 0 0
sp_client_order 20311 20311 0 0.00 3 16635 372 0 0
sp_customer_reserve 456328 456328 0 0.00 0 40355 185 0 0
sp_fill_shopping_cart 31157 31157 0 0.00 1 11965 38 0 0
sp_fill_shopping_cart_clo_res 456319 456319 0 0.00 0 15962 14 0 0
sp_get_clo_for_invoice 4926 4926 0 0.00 0 37536 354 0 0
sp_kill_cost_storno 16491 16491 0 0.00 0 5865 60 0 0
sp_kill_qstorno_ret_qs2qd 1136467 1136467 0 0.00 0 16063 33 0 0
sp_kill_qty_storno 598564 598564 0 0.00 0 28176 72 0 0
sp_lock_selected_doc 20330 20330 0 0.00 0 7095 5 0 0
sp_make_cost_storno 27376 27376 0 0.00 0 12920 62 0 0
sp_multiply_rows_for_pdistr 27375 27375 0 0.00 0 16549 32 0 0
sp_multiply_rows_for_qdistr 20311 20311 0 0.00 0 11999 160 0 0
sp_pay_from_customer 6899 6899 0 0.00 4 16556 111 0 0
sp_pay_to_supplier 5819 5819 0 0.00 5 16181 130 0 0
sp_payment_common 12718 12718 0 0.00 1 11561 28 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 8345 302 0 0
sp_qd_handle_on_invoice_upd_sts 10608 10608 0 0.00 0 28175 363 0 0
sp_qd_handle_on_reserve_upd_sts 9730 9730 0 0.00 0 12044 59 0 0
sp_reserve_write_off 9732 9732 0 0.00 6 13766 185 0 0
sp_supplier_invoice 5516 5516 0 0.00 6 72305 3320 0 0
sp_supplier_order 5339 5339 0 0.00 5 19612 1247 0 0
srv_aggregate_perf_data 98 98 0 0.00 3455 343887 22569 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2134 112554 43769 0 0
srv_find_qd_qs_mism 1053826 1053826 0 0.00 0 16998 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 49 58592 1166 0 0
srv_make_money_saldo 758 758 0 0.00 1 754 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 197415 386768 276617 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 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 4936 4936 0 0.00 0 17856 174 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 11400 117 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 997 9 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 4808 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 197 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 7619 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 7265 34 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 29770 1619 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 5092 20 0 0
t$perf-norm:sp_client_order 20311 20311 0 0.00 0 9455 20 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 5838 16 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 8109 13 0 0
t$perf-norm:sp_pay_to_supplier 5819 5819 0 0.00 0 5474 11 0 0
t$perf-norm:sp_reserve_write_off 9732 9732 0 0.00 0 5276 12 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 18174 26 0 0
t$perf-norm:sp_supplier_order 5339 5339 0 0.00 0 5210 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1523 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 50 1 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 3956 32 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1763 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10046 9850 196 1.95 0 6464 21 0 196
v_all_customers 36744 36744 0 0.00 0 21 1 0 0
v_all_suppliers 30128 30128 0 0.00 0 158 1 0 0
v_all_wares 40622 40622 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 2546 9 0 0
v_cancel_client_order 3530 3530 0 0.00 0 6240 9 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 5382 12 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 726 3 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 2285 8 0 0
v_cancel_supplier_invoice 872 846 26 2.98 0 2018 17 0 26
v_cancel_supplier_order 796 796 0 0.00 0 3915 14 0 0
v_cancel_write_off 1968 1968 0 0.00 0 871 12 0 0
v_max_id_clo_ord 5338 5338 0 0.00 0 3042 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 4161 12 0 0
v_max_id_ord_sup 5507 5507 0 0.00 0 4575 4 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 103 2 0 0
v_max_non_paid_realizn 3819 3819 0 0.00 0 956 3 0 0
v_min_id_clo_ord 5338 5338 0 0.00 0 6269 9 0 0
v_min_id_clo_res 995 995 0 0.00 0 2674 21 0 0
v_min_id_ord_sup 5507 5507 0 0.00 0 8338 13 0 0
v_min_non_paid_invoice 5819 5819 0 0.00 0 11554 24 0 0
v_min_non_paid_realizn 6899 6899 0 0.00 0 9655 22 0 0
v_our_firm 10038 10038 0 0.00 0 12 1 0 0
v_reserve_write_off 19460 19460 0 0.00 0 5655 5 0 0
x_lock_depdocs_on_canc_invoice 11585 11585 0 0.00 0 18269 127 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 3735 364 0 0
x_make_qstorno_1000_1200 5339 5339 0 0.00 1 19532 1164 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 2 72294 3192 0 0
x_make_qstorno_2100_3300 456327 456327 0 0.00 0 40344 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2022 19:38:58 to 02-01-2022 19:39:02.

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
17:08:06 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:07 33568 8192000000 9899050640 10212900864 986319984 10.746 100 86 172 0 1015917184 6313376 23283824
17:10:47 25598 8192000000 9953581248 10276663296 1031796048 11.186 100 86 172 0 1062142704 7956208 22390448
17:12:21 23402 8192000000 10000438368 10339430400 1082986032 11.676 100 83 166 0 1112683808 9460032 20237744
17:13:48 12283 8192000000 10045940592 10399207424 1130786176 12.129 100 78 154 2 1159873072 10715712 18371184
17:15:02 6775 8192000000 10086209632 10449866752 1176225728 12.555 100 79 158 1 1207098592 13067104 17805760
17:16:18 4391 8192000000 10126200624 10496692224 1218030928 12.943 100 78 157 1 1252281952 16819168 17431856
17:17:24 2912 8192000000 10164176416 10545672192 1260920784 13.338 100 81 163 0 1290161776 10754320 18486672
17:18:28 2636 8192000000 10190856256 10572267520 1278242784 13.497 100 85 169 0 1310262480 13181984 18837712
17:19:32 2359 8192000000 10192391472 10586742784 1291357744 13.617 100 71 142 2 1318008704 10646336 16004624
17:20:36 46711 8192000000 10204277392 10607345664 1302755776 13.720 100 44 90 0 1318898288 4784064 11358448
17:22:24 8662 8192000000 10202263360 10620792832 1315481584 13.836 100 26 50 0 1331092704 9612704 5998416
17:23:35 7389 8192000000 10209309488 10620121088 1318392880 13.862 100 12 24 1 1326705152 5805840 2506432
17:24:42 8921 8192000000 10220306576 10636955648 1325674064 13.928 100 29 58 1 1341885872 9689152 6522656
17:26:00 29377 8192000000 10248448096 10646052864 1330180928 13.969 100 65 131 0 1351968160 5509280 16277952
17:27:30 3766 8192000000 10219701856 10630041600 1289566256 13.600 97 44 90 1 1312123968 12421968 10135744
17:28:36 8720 8192000000 10193316496 10605203456 1283722800 13.547 98 25 50 0 1295248768 6356480 5169488
17:29:45 8911 8192000000 10154069440 10555449344 1222580144 12.986 96 20 40 0 1232331344 5485296 4265904
17:30:56 23683 8192000000 10128704176 10522087424 1145989152 12.272 91 26 52 0 1157045520 5127520 5928848
17:32:21 8104 8192000000 10149664400 10553065472 1259134240 13.322 100 24 48 1 1271044368 6246192 5663936
17:33:31 7924 8192000000 10173370368 10576650240 1273998048 13.458 100 30 60 1 1286895488 5736736 7160704
17:34:44 81197 8192000000 10172987184 10587410432 1287424480 13.581 100 24 48 1 1298226272 5089232 5712560
17:37:05 6718 8192000000 10196912768 10607374336 1301441152 13.708 100 22 42 1 1312216064 4966528 5808384
17:38:13 25502 8192000000 10231514288 10639425536 1320718480 13.883 100 37 74 0 1337230512 7156944 9355088
17:39:46 33338 8192000000 10259607680 10677559296 1354849936 14.191 100 41 80 0 1377349456 13439184 9060336
17:41:23 62140 8192000000 10271780288 10704158720 1365255024 14.285 100 28 56 2 1385943392 13775920 6912448
17:43:28 9875 8192000000 10299430560 10724519936 1383447952 14.447 100 44 88 1 1411665840 17706144 10511744
17:44:45 8272 8192000000 10317297776 10739671040 1398094800 14.578 100 61 122 0 1432694336 20492800 14106736
17:45:58 3019 8192000000 10322265072 10743353344 1388301760 14.491 99 70 138 0 1425873600 21377888 16193952
17:47:05 2819 8192000000 10344036032 10759729152 1407132000 14.658 100 81 162 1 1450071184 23595232 19343952
17:48:13 11423 8192000000 10332384752 10749976576 1389088896 14.498 99 69 138 1 1434682640 28227792 17365952
17:49:29 16021 8192000000 10318196416 10738659328 1372628224 14.351 98 56 113 0 1409996928 23925152 13443552
17:50:47 7465 8192000000 10338470656 10756464640 1393809568 14.540 100 74 149 0 1445725920 33449088 18467264
17:51:58 16534 8192000000 10325614096 10747330560 1389315248 14.500 100 61 122 1 1438814320 34174064 15325008
17:53:22 3549 8192000000 10335419744 10749505536 1392664480 14.530 100 78 159 0 1438114416 25129216 20320720
17:54:31 2133 8192000000 10328093776 10738864128 1371083376 14.337 99 73 144 0 1420480272 31827216 17569680
17:55:38 7849 8192000000 10326936112 10751229952 1389156896 14.498 100 42 84 2 1425509456 25706176 10646384
17:56:55 5095 8192000000 10326223936 10736041984 1372706080 14.351 99 66 131 2 1428154512 38928848 16519584
17:58:05 4110 8192000000 10347290272 10751954944 1379078064 14.408 100 81 161 4 1444117120 45714464 19324592
17:59:13 8298 8192000000 10336334880 10756222976 1388921504 14.496 99 44 88 1 1445039424 44284320 11833600
18:00:35 12346 8192000000 10344920112 10770276352 1377085376 14.390 98 37 74 1 1434076192 46219152 10771664
18:01:56 10292 8192000000 10354822320 10782531584 1389071280 14.498 99 43 86 1 1456197376 54889392 12236704
18:03:12 17186 8192000000 10346159648 10764939264 1370254848 14.329 99 37 74 1 1445652960 64170288 11227824
18:04:46 18852 8192000000 10341701344 10775580672 1377940064 14.398 100 35 70 1 1449052880 61092992 10019824
18:06:14 14666 8192000000 10313699648 10768396288 1355506480 14.197 100 21 42 1 1430770672 68396176 6868016
18:07:44 36613 8192000000 10267295408 10728796160 1336375120 14.025 99 15 30 1 1374720096 34935904 3409072
18:09:21 60321 8192000000 10265881648 10705305600 1352854688 14.173 99 26 50 1 1366188592 8276224 5057680
18:11:36 55401 8192000000 10263791600 10712289280 1358289856 14.222 99 11 22 0 1366780736 6172368 2318512
18:13:47 48277 8192000000 10293604464 10746953728 1386802384 14.477 100 18 36 0 1402539776 11944416 3792976
18:15:39 59974 8192000000 10300733584 10756943872 1383120160 14.444 99 25 50 0 1403036800 14077040 5839600
18:17:52 61693 8192000000 10295465200 10758619136 1379839424 14.415 99 23 47 0 1405326880 20142720 5344736
18:19:54 59595 8192000000 10308614080 10761367552 1400986432 14.604 100 10 20 0 1414746368 11358576 2401360
18:21:55 71148 8192000000 10304413040 10766004224 1386597776 14.475 99 10 20 0 1406344432 17731600 2015056
18:24:35 87524 8192000000 10310227760 10770296832 1390072768 14.507 99 15 31 0 1408950704 15543264 3334672
18:27:05 48932 8192000000 10304523824 10757500928 1364155104 14.275 97 21 42 0 1388745824 19608288 4982432
18:28:59 56352 8192000000 10301218096 10777972736 1388701568 14.494 99 11 22 1 1422364400 30758912 2903920
18:32:02 66403 8192000000 10321267664 10770178048 1399648272 14.592 100 17 36 1 1422417200 18823184 3945744
18:34:09 50791 8192000000 10328220528 10781196288 1392376928 14.527 99 30 60 2 1423273648 24096016 6800704
18:36:01 65625 8192000000 10304336288 10765168640 1383575088 14.449 99 7 14 0 1405783664 21088832 1119744
18:38:07 48116 8192000000 10281224544 10749476864 1373656560 14.360 99 19 34 0 1389282528 11738480 3887488
18:40:25 40483 8192000000 10268407824 10724278272 1341495424 14.071 98 18 37 0 1358256880 12721408 4040048
18:42:41 52311 8192000000 10269618112 10721402880 1338252784 14.042 98 16 32 0 1350123904 8408512 3462608
18:44:36 55958 8192000000 10269962880 10713837568 1301029344 13.705 95 17 34 1 1313377360 8315920 4032096
18:46:35 53684 8192000000 10258905488 10701950976 1281085488 13.523 94 14 28 0 1294044176 9745248 3213440
18:48:29 69274 8192000000 10263816496 10711650304 1314392560 13.826 96 17 34 0 1334875216 16918704 3563952
18:50:39 61472 8192000000 10270565936 10710310912 1311712400 13.802 96 17 34 0 1324477232 9143936 3620896
18:52:41 112554 8192000000 10258357984 10709327872 1342891840 14.083 99 25 50 1 1355401792 7104576 5405376
18:55:35 50538 8192000000 10239049712 10678059008 1305417344 13.744 97 22 44 1 1325581232 15200640 4963248
18:57:26 73929 8192000000 10227988624 10672316416 1249996960 13.238 93 20 40 0 1269981568 14945696 5038912
18:59:41 47712 8192000000 10214272576 10663071744 1292858304 13.630 98 12 24 1 1311370304 15896896 2615104
19:01:33 89117 8192000000 10234263392 10678669312 1322142640 13.896 99 12 24 0 1335283088 10158960 2981488
19:04:44 50746 8192000000 10243794928 10699763712 1343140608 14.086 100 13 25 1 1356780816 10522608 3117600
19:06:36 23404 8192000000 10272039504 10717315072 1366249872 14.293 100 30 60 0 1382200112 8969440 6980800
19:08:03 52967 8192000000 10292125856 10743062528 1377835680 14.397 100 15 30 0 1396973824 15822080 3316064
19:10:15 92510 8192000000 10290664544 10751062016 1348835808 14.137 97 10 20 0 1362777504 11675472 2266224
19:12:58 50020 8192000000 10298893440 10755719168 1365535856 14.287 98 21 40 1 1384471536 14593008 4342672
19:14:48 54090 8192000000 10326031216 10770800640 1384618688 14.458 99 37 74 0 1405860384 13544032 7697664
19:16:48 52088 8192000000 10320905520 10769408000 1398601040 14.583 100 16 32 1 1415701792 13665200 3435552
19:19:46 105621 8192000000 10304892432 10770046976 1379711536 14.414 98 11 22 0 1398997776 16714064 2572176
19:22:59 25456 8192000000 10304030160 10775068672 1393230832 14.535 99 29 58 1 1414143776 14338864 6574080
19:24:32 56393 8192000000 10298925984 10760859648 1368352656 14.312 97 12 24 0 1387903872 16750704 2800512
19:26:31 87489 8192000000 10293360288 10769031168 1363562928 14.269 97 15 30 1 1386701168 19558416 3579824
19:29:42 79415 8192000000 10296152032 10759397376 1357368064 14.214 98 12 24 0 1382484816 22276016 2840736
19:32:02 39864 8192000000 10267185456 10709467136 1294091264 13.641 94 28 56 0 1311198080 11070400 6036416
19:33:42 56651 8192000000 10270044400 10715418624 1329360416 13.961 97 17 32 0 1347408656 14227280 3820960
19:35:40 40989 8192000000 10277754656 10713657344 1316045248 13.841 97 40 80 0 1341420048 16433840 8940960
19:37:27 48873 8192000000 9221439888 9484890112 367853968 4.297 28 1 2 0 377963776 10067456 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-01-2022 19:39:04 to 02-01-2022 19:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 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 02-01-2022 19:39:19 to 02-01-2022 19:39:21.

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          237446
MON$OLDEST_ACTIVE               237447
MON$OLDEST_SNAPSHOT             237447
MON$NEXT_TRANSACTION            237447
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                       2036256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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'

02.01.22 19:39:22. 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-01-02 19:39:25 No severe PSQL-related problems occured
02.01.22 19:39:26. End of report.