Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.05.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 16724388 kB
MemAvailable 31407808 kB
Buffers 0 kB
Cached 15005416 kB
SwapCached 2544 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-05-25 01:38:22
DTS_END                         2022-05-25 01:38:22.9040
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/46014
TRN_ID                          242733
ATT_ID                          721
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: 8841.78 18728 1061014
customer order: creation 175.87 345 21104
customer order: refuse 15.06 360 1807
order to supplier: creation 46.93 1285 5631
order to supplier: removal 3.33 83732 399
invoice (draft): creation 45.67 3616 5480
invoice (draft): removal 48.45 693 5814
invoice accept: apply 42.01 19222 5041
invoice accept: cancel 43.42 801 5210
customer reserve: creation 3837.33 193 460480
customer reserve: removal 4298.52 50 515823
realization accept: apply 85.18 167 10222
realization accept: cancel 69.11 117 8293
payment from customer: creation 60.52 121 7262
payment from customer: removal 5.56 86 667
payment to supplier: creation 49.29 119 5915
payment to supplier: removal 3.45 129 414
service: total inventory turnovers 4.47 992 536
service: total monetary turnovers 6.82 25 818
service: aggregate perf. data 0.78 26010 94
service: refresh index statistics 0.03 236489 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-05-2022 01:39:43 to 25-05-2022 01:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7616
TEST_TIME 2 6824
TEST_TIME 3 6033
TEST_TIME 4 5830
TEST_TIME 5 6934
TEST_TIME 6 7448
TEST_TIME 7 8027
TEST_TIME 8 7966
TEST_TIME 9 7920
TEST_TIME 10 7835
TEST_TIME 11 7963
TEST_TIME 12 7890
TEST_TIME 13 8218
TEST_TIME 14 8324
TEST_TIME 15 8334
TEST_TIME 16 8446
TEST_TIME 17 9028
TEST_TIME 18 8718
TEST_TIME 19 9005
TEST_TIME 20 9045
TEST_TIME 21 9744
TEST_TIME 22 10035
TEST_TIME 23 10107
TEST_TIME 24 10593
TEST_TIME 25 10209
TEST_TIME 26 10128
TEST_TIME 27 10018
TEST_TIME 28 10164
TEST_TIME 29 10010
TEST_TIME 30 9778
TEST_TIME 31 9592
TEST_TIME 32 9838
TEST_TIME 33 9665
TEST_TIME 34 9521
TEST_TIME 35 9692
TEST_TIME 36 9535
TEST_TIME 37 9534
TEST_TIME 38 9409
TEST_TIME 39 9369
TEST_TIME 40 9283
TEST_TIME 41 9241
TEST_TIME 42 9270
TEST_TIME 43 9114
TEST_TIME 44 9034
TEST_TIME 45 8844
TEST_TIME 46 9019
TEST_TIME 47 8987
TEST_TIME 48 9063
TEST_TIME 49 9119
TEST_TIME 50 9141
TEST_TIME 51 9137
TEST_TIME 52 9115
TEST_TIME 53 9126
TEST_TIME 54 9124
TEST_TIME 55 9124
TEST_TIME 56 9240
TEST_TIME 57 9376
TEST_TIME 58 9340
TEST_TIME 59 9333
TEST_TIME 60 9308
TEST_TIME 61 9236
TEST_TIME 62 9101
TEST_TIME 63 9162
TEST_TIME 64 9184
TEST_TIME 65 9101
TEST_TIME 66 9052
TEST_TIME 67 8985
TEST_TIME 68 8960
TEST_TIME 69 8905
TEST_TIME 70 8878
TEST_TIME 71 8831
TEST_TIME 72 9013
TEST_TIME 73 8993
TEST_TIME 74 8996
TEST_TIME 75 9025
TEST_TIME 76 9081
TEST_TIME 77 9095
TEST_TIME 78 9110
TEST_TIME 79 9102
TEST_TIME 80 9096
TEST_TIME 81 9152
TEST_TIME 82 9126
TEST_TIME 83 9060
TEST_TIME 84 9037
TEST_TIME 85 9009
TEST_TIME 86 9009
TEST_TIME 87 8983
TEST_TIME 88 8945
TEST_TIME 89 8925
TEST_TIME 90 8879
TEST_TIME 91 8817
TEST_TIME 92 8843
TEST_TIME 93 8818
TEST_TIME 94 8847
TEST_TIME 95 8920
TEST_TIME 96 8905
TEST_TIME 97 8956
TEST_TIME 98 8986
TEST_TIME 99 8940
TEST_TIME 100 8964
TEST_TIME 101 9009
TEST_TIME 102 9091
TEST_TIME 103 9095
TEST_TIME 104 9091
TEST_TIME 105 9096
TEST_TIME 106 9060
TEST_TIME 107 9004
TEST_TIME 108 9038
TEST_TIME 109 8992
TEST_TIME 110 8996
TEST_TIME 111 8955
TEST_TIME 112 8927
TEST_TIME 113 8907
TEST_TIME 114 8845
TEST_TIME 115 8808
TEST_TIME 116 8872
TEST_TIME 117 8883
TEST_TIME 118 8858
TEST_TIME 119 8896
TEST_TIME 120 8849

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-05-2022 01:39:47 to 25-05-2022 01:39:50.

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 1487252 1487252 0 0.00 0 16429 7 0 0
doc_list_biud 1487252 1487252 0 0.00 0 44512 23 0 0
sp_add_invoice_to_stock 5189 5041 148 2.85 37 167219 19222 0 148
sp_cancel_adding_invoice 5210 5210 0 0.00 5 47967 801 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 11799 360 0 0
sp_cancel_customer_reserve 515823 515823 0 0.00 1 29413 51 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 5673 86 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 9756 129 0 0
sp_cancel_supplier_invoice 5825 5814 11 0.18 2 24303 694 0 11
sp_cancel_supplier_order 399 399 0 0.00 5 954304 83733 0 0
sp_cancel_write_off 8293 8293 0 0.00 1 18394 117 0 0
sp_client_order 21104 21104 0 0.00 3 18667 345 0 0
sp_customer_reserve 460480 460480 0 0.00 0 57026 193 0 0
sp_fill_shopping_cart 32214 32214 0 0.00 1 17879 41 0 0
sp_fill_shopping_cart_clo_res 460467 460467 0 0.00 0 18309 14 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 27547 352 0 0
sp_kill_cost_storno 14584 14584 0 0.00 0 16417 57 0 0
sp_kill_qstorno_ret_qs2qd 1046152 1046152 0 0.00 0 29405 28 0 0
sp_kill_qty_storno 552595 552595 0 0.00 0 44512 61 0 0
sp_lock_selected_doc 21062 21062 0 0.00 0 8828 6 0 0
sp_make_cost_storno 28426 28426 0 0.00 0 10181 50 0 0
sp_multiply_rows_for_pdistr 28426 28426 0 0.00 0 11964 28 0 0
sp_multiply_rows_for_qdistr 21104 21104 0 0.00 0 13502 153 0 0
sp_pay_from_customer 7262 7262 0 0.00 4 16549 121 0 0
sp_pay_to_supplier 5915 5915 0 0.00 5 12103 120 0 0
sp_payment_common 13177 13177 0 0.00 1 16537 33 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 11794 337 0 0
sp_qd_handle_on_invoice_upd_sts 10237 10237 0 0.00 0 44511 341 0 0
sp_qd_handle_on_reserve_upd_sts 10222 10222 0 0.00 0 16026 56 0 0
sp_reserve_write_off 10222 10222 0 0.00 6 16137 167 0 0
sp_supplier_invoice 5480 5480 0 0.00 7 95653 3617 0 0
sp_supplier_order 5631 5631 0 0.00 5 30331 1285 0 0
srv_aggregate_perf_data 94 94 0 0.00 2664 467084 26011 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1407 140641 49045 0 0
srv_find_qd_qs_mism 1012797 1012797 0 0.00 0 9590 6 0 0
srv_make_invnt_saldo 536 536 0 0.00 30 25376 993 0 0
srv_make_money_saldo 818 818 0 0.00 1 2247 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 108417 311064 236489 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5041 5041 0 0.00 0 14149 151 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 10935 112 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 6055 12 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 1162 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 848 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1137 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 918 13 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 20404 1353 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 2411 9 0 0
t$perf-norm:sp_client_order 21104 21104 0 0.00 0 19566 19 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 4297 10 0 0
t$perf-norm:sp_pay_from_customer 7262 7262 0 0.00 0 6463 12 0 0
t$perf-norm:sp_pay_to_supplier 5915 5915 0 0.00 0 3042 10 0 0
t$perf-norm:sp_reserve_write_off 10222 10222 0 0.00 0 9462 11 0 0
t$perf-norm:sp_supplier_invoice 5480 5480 0 0.00 0 3134 16 0 0
t$perf-norm:sp_supplier_order 5631 5631 0 0.00 0 18986 22 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 179 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 38 1 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 6936 38 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 1379 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10344 10048 296 2.86 0 4242 17 0 296
v_all_customers 37896 37896 0 0.00 0 67 1 0 0
v_all_suppliers 30386 30386 0 0.00 0 20 1 0 0
v_all_wares 42208 42208 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1424 7 0 0
v_cancel_client_order 3614 3614 0 0.00 0 4026 5 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 4555 13 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 4278 16 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 229 3 0 0
v_cancel_supplier_invoice 832 810 22 2.64 0 4387 19 0 22
v_cancel_supplier_order 798 798 0 0.00 0 346 4 0 0
v_cancel_write_off 2046 2046 0 0.00 0 1805 12 0 0
v_max_id_clo_ord 5631 5631 0 0.00 0 984 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 1563 11 0 0
v_max_id_ord_sup 5479 5479 0 0.00 0 4596 6 0 0
v_max_non_paid_invoice 1832 1832 0 0.00 0 492 2 0 0
v_max_non_paid_realizn 4059 4059 0 0.00 0 4941 6 0 0
v_min_id_clo_ord 5631 5631 0 0.00 0 2546 6 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 8600 18 0 0
v_min_id_ord_sup 5479 5479 0 0.00 0 6234 9 0 0
v_min_non_paid_invoice 5915 5915 0 0.00 0 6317 19 0 0
v_min_non_paid_realizn 7262 7262 0 0.00 0 16532 31 0 0
v_our_firm 10718 10718 0 0.00 0 17 1 0 0
v_reserve_write_off 20444 20444 0 0.00 0 4232 5 0 0
x_lock_depdocs_on_canc_invoice 10619 10619 0 0.00 0 18459 116 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 9771 371 0 0
x_make_qstorno_1000_1200 5631 5631 0 0.00 1 30319 1201 0 0
x_make_qstorno_1200_2000 5480 5480 0 0.00 2 95543 3466 0 0
x_make_qstorno_2100_3300 460480 460480 0 0.00 0 57024 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-05-2022 01:39:51 to 25-05-2022 01:39:55.

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:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:27 18454 8192000000 9919272640 10233991168 1002036160 10.898 100 91 182 0 1031788960 6080976 23671824
23:10:51 24387 8192000000 9958381904 10285006848 1040210912 11.267 100 84 169 0 1069958080 8510656 21236512
23:12:21 20154 8192000000 9991729568 10324250624 1072374848 11.575 100 88 176 0 1104701136 10455568 21870720
23:13:45 16563 8192000000 10021161440 10366054400 1106033792 11.895 100 85 170 0 1137801104 11452720 20314592
23:15:09 12110 8192000000 10086790528 10449076224 1173163440 12.526 100 81 162 1 1204170592 12435360 18571792
23:16:23 4274 8192000000 10134195440 10506838016 1226106432 13.018 100 84 168 1 1258268400 13024416 19137552
23:17:29 2622 8192000000 10156288064 10533367808 1249818784 13.237 100 84 168 0 1281829968 13199024 18812160
23:18:34 1634 8192000000 10210022832 10590965760 1291032320 13.614 100 88 177 1 1324132352 12813408 20286624
23:19:37 22040 8192000000 10207468640 10602815488 1298449280 13.681 100 56 113 1 1320662096 9162032 13050784
23:21:00 8870 8192000000 10201134864 10609008640 1307640336 13.765 100 19 36 0 1318041824 5725568 4675920
23:22:09 13074 8192000000 10193687680 10615279616 1315380992 13.835 100 13 26 1 1323544688 5442352 2721344
23:23:26 54482 8192000000 10218319856 10637746176 1326452544 13.935 100 48 96 0 1344718896 6064544 12201808
23:25:22 7741 8192000000 10231320880 10651033600 1335631904 14.018 100 34 67 1 1354598512 12063904 6902704
23:26:30 7350 8192000000 10226610640 10647121920 1337674704 14.036 100 23 46 0 1348637264 5850144 5112416
23:27:44 10786 8192000000 10214019808 10632544256 1317222416 13.852 99 23 46 0 1328148800 5123328 5803056
23:28:56 8359 8192000000 10230804160 10619744256 1302197504 13.715 99 87 172 2 1330132416 7229760 20705152
23:30:05 2772 8192000000 10172906656 10573303808 1274142080 13.459 100 37 74 4 1289248992 7316176 7790736
23:31:08 6486 8192000000 10134158368 10526527488 1219810128 12.960 98 31 62 1 1233681200 6718528 7152544
23:32:14 57159 8192000000 10159139584 10559692800 1245837968 13.200 98 31 61 0 1258530192 5101984 7590240
23:34:12 9383 8192000000 10186477968 10595561472 1292540384 13.627 100 31 62 1 1306160816 6826160 6794272
23:35:24 8991 8192000000 10192486368 10605326336 1298380944 13.681 100 16 30 1 1306433808 4904736 3148128
23:36:34 5749 8192000000 10199681264 10615726080 1308300752 13.771 100 24 48 2 1319870592 5363520 6206320
23:37:45 21790 8192000000 10210266976 10627645440 1315790288 13.839 100 22 43 1 1330187888 9889248 4508352
23:39:13 29608 8192000000 10254887808 10667450368 1342688016 14.082 100 49 98 0 1365216176 11808736 10719424
23:40:45 3996 8192000000 10277059520 10688389120 1364388304 14.277 100 61 123 1 1393378272 15160560 13829408
23:41:58 9784 8192000000 10286098256 10697113600 1366635648 14.297 100 56 112 0 1396572000 15948544 13987808
23:43:12 15279 8192000000 10287091024 10705989632 1372459376 14.349 100 51 97 2 1399284832 15491024 11334432
23:44:32 5987 8192000000 10300350704 10713903104 1376019552 14.381 99 65 128 2 1406698608 16479744 14199312
23:45:40 1987 8192000000 10321378688 10740269056 1393914448 14.541 100 71 142 2 1430365328 19420928 17029952
23:46:47 10891 8192000000 10319878640 10737827840 1395277744 14.553 100 74 148 1 1432076336 19727328 17071264
23:48:03 11368 8192000000 10324038720 10751397888 1400550432 14.600 100 55 110 1 1434332240 19900064 13881744
23:49:21 9610 8192000000 10326932480 10748579840 1391338400 14.518 99 67 131 2 1430642992 23679552 15625040
23:50:37 3866 8192000000 10329203616 10739384320 1387777728 14.486 100 76 152 1 1432199824 26269072 18153024
23:51:46 4433 8192000000 10328658160 10739335168 1384896256 14.460 100 80 155 1 1428197760 24070832 19230672
23:52:53 7691 8192000000 10345280832 10763317248 1406205024 14.650 100 64 128 0 1449546480 28027168 15314288
23:54:03 2924 8192000000 10300252048 10703327232 1349754352 14.145 99 81 161 2 1401681216 32410608 19516256
23:55:11 1406 8192000000 10325013296 10726662144 1372381424 14.348 100 83 164 1 1420610144 28353936 19874784
23:56:19 14382 8192000000 10281583408 10701312000 1344101840 14.094 99 32 64 2 1380123184 27207824 8813520
23:57:37 16031 8192000000 10277362704 10698993664 1345982976 14.111 100 27 54 2 1386777440 33188304 7606160
23:59:03 12033 8192000000 10267802528 10695286784 1328660096 13.955 99 27 53 1 1369847648 33335088 7852464
00:00:21 37393 8192000000 10262169872 10693566464 1320463664 13.881 98 17 34 1 1357630912 32477184 4690064
00:02:04 17879 8192000000 10251580608 10681024512 1304505888 13.736 98 21 41 1 1340843968 31395328 4942752
00:03:22 56446 8192000000 10250509664 10675073024 1323250640 13.906 99 42 82 2 1347517600 12844608 11422352
00:05:21 5642 8192000000 10258908864 10684547072 1343992080 14.093 100 52 104 1 1371226992 14493008 12741904
00:06:29 22727 8192000000 10246968592 10694324224 1343319952 14.087 100 15 29 0 1365764640 18813200 3631488
00:08:53 70322 8192000000 10289815776 10722545664 1367784032 14.307 100 24 48 1 1391299456 17564688 5950736
00:11:08 38814 8192000000 10286695072 10720923648 1366839984 14.299 99 35 66 2 1385615520 10418528 8357008
00:13:17 93014 8192000000 10298448544 10735050752 1384360272 14.456 100 29 58 0 1399921168 8397744 7163152
00:15:52 77319 8192000000 10274597008 10731012096 1374525920 14.368 99 12 23 0 1390416256 13029008 2861328
00:18:10 24320 8192000000 10289453904 10728296448 1386563104 14.475 100 31 60 3 1401255472 6818512 7873856
00:19:35 87274 8192000000 10284177152 10742468608 1379086848 14.408 99 17 33 1 1398303264 14997264 4219152
00:22:29 67795 8192000000 10293645824 10747248640 1381835808 14.433 99 17 34 1 1403124480 17275824 4012848
00:24:59 18242 8192000000 10282273920 10735796224 1391229568 14.517 100 26 50 2 1405257328 7978864 6048896
00:26:26 110272 8192000000 10287066288 10747326464 1362029024 14.256 98 10 20 0 1375093952 10544624 2520304
00:29:16 99535 8192000000 10294920160 10750599168 1342259552 14.078 96 22 44 0 1358931360 11853984 4817824
00:31:57 38873 8192000000 10306801104 10750935040 1388046976 14.488 100 39 78 0 1409597408 12291840 9258592
00:33:39 33062 8192000000 10321735600 10765320192 1400337792 14.598 100 31 62 0 1427503792 19987440 7178560
00:35:16 140640 8192000000 10293339408 10729672704 1314949872 13.831 94 5 10 0 1331708704 15779424 979408
00:38:44 112322 8192000000 10281979200 10721087488 1354842448 14.191 98 26 52 0 1380218384 19560944 5814992
00:41:52 66038 8192000000 10242613792 10697715712 1330309488 13.970 98 21 41 1 1352406832 17182800 4914544
00:43:58 49296 8192000000 10225907664 10669654016 1278516224 13.499 95 7 14 0 1287875376 7863728 1495424
00:45:56 47785 8192000000 10263296096 10715369472 1356306832 14.204 100 8 16 0 1368627920 10513472 1807616
00:47:45 78678 8192000000 10271378384 10719027200 1332607888 13.991 97 12 24 0 1353558608 17841760 3108960
00:50:06 73779 8192000000 10262684656 10692485120 1272921888 13.448 93 14 28 2 1284787712 8349232 3516592
00:52:20 71372 8192000000 10254175712 10695028736 1311927568 13.804 97 21 42 1 1334640432 17775552 4937312
00:54:35 117174 8192000000 10285043552 10724007936 1333785904 14.001 98 26 52 0 1355144752 16091600 5267248
00:58:04 65348 8192000000 10278619760 10725122048 1334567296 14.008 98 16 32 0 1352161680 14146784 3447600
01:00:09 56017 8192000000 10295443008 10736615424 1372525280 14.350 100 22 44 1 1389625392 12714752 4385360
01:02:07 84167 8192000000 10278387600 10725134336 1367629024 14.306 99 35 70 1 1388914720 13225648 8060048
01:04:43 52470 8192000000 10268049376 10723528704 1353485712 14.179 98 10 20 0 1363368672 7910176 1972784
01:06:44 67740 8192000000 10280381584 10734792704 1372803200 14.352 99 15 30 1 1383973488 7894016 3276272
01:09:07 97691 8192000000 10289904784 10754183168 1355632400 14.198 97 9 18 0 1368694528 11284752 1777376
01:11:45 41339 8192000000 10308452416 10758344704 1396587664 14.565 100 28 56 2 1418771744 15103744 7080336
01:13:29 51807 8192000000 10304264560 10752069632 1369188576 14.320 98 23 46 1 1385273472 10754944 5329952
01:15:26 56744 8192000000 10300057760 10762510336 1396403760 14.563 100 14 28 0 1414226640 14468960 3353920
01:17:30 82903 8192000000 10316408224 10775252992 1402881536 14.621 100 16 32 0 1424665824 17658752 4125536
01:20:39 98681 8192000000 10320344592 10779406336 1368961568 14.318 97 18 37 1 1403042656 30144832 3936256
01:23:20 23070 8192000000 10300142064 10739982336 1345868976 14.110 96 33 66 0 1363431488 10517280 7045232
01:24:44 83789 8192000000 10303795088 10743103488 1383932560 14.452 100 24 48 0 1399778640 10373648 5472432
01:27:12 43090 8192000000 10274933120 10739683328 1343973232 14.093 97 11 22 0 1363511408 17620288 1917888
01:28:55 64468 8192000000 10244607440 10706374656 1334035808 14.004 98 5 10 0 1344134768 8900352 1198608
01:31:00 55725 8192000000 10268752880 10719571968 1353458288 14.179 99 19 38 1 1368877008 11229040 4189680
01:33:06 57278 8192000000 10280122992 10739015680 1379887888 14.416 100 18 35 1 1401144736 16805136 4451712
01:35:04 99470 8192000000 10267422752 10716782592 1281663328 13.528 93 12 24 0 1306827232 22548208 2615696
01:37:44 41536 8192000000 9514236064 9843789824 649882048 7.350 48 13 25 0 661167088 8406976 2878064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-05-2022 01:39:57 to 25-05-2022 01:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-05-2022 01:40:12 to 25-05-2022 01:40:14.

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          241555
MON$OLDEST_ACTIVE               243038
MON$OLDEST_SNAPSHOT             243038
MON$NEXT_TRANSACTION            243038
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                       2005248
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.33594 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33594 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33594 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33594 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Wed May 25 01:38:38 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Wed May 25 01:38:38 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

25.05.22 01:40:15. 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-05-25 01:40:18 No severe PSQL-related problems occured
25.05.22 01:40:19. End of report.