Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2022 16: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 16063932 kB
MemAvailable 30886976 kB
Buffers 0 kB
Cached 15628068 kB
SwapCached 2532 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-13 16:38:27
DTS_END                         2022-08-13 16:38:27.5900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49636
TRN_ID                          236160
ATT_ID                          676
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: 9196.83 17283 1103620
customer order: creation 172.80 401 20736
customer order: refuse 14.45 394 1734
order to supplier: creation 44.93 1290 5392
order to supplier: removal 3.60 96833 432
invoice (draft): creation 45.18 3339 5422
invoice (draft): removal 51.88 732 6225
invoice accept: apply 42.20 18601 5064
invoice accept: cancel 47.33 783 5680
customer reserve: creation 3850.23 187 462028
customer reserve: removal 4627.48 60 555298
realization accept: apply 83.70 210 10044
realization accept: cancel 83.35 113 10002
payment from customer: creation 59.03 139 7084
payment from customer: removal 5.55 108 666
payment to supplier: creation 50.32 159 6038
payment to supplier: removal 3.11 58 373
service: total inventory turnovers 4.36 1238 523
service: total monetary turnovers 6.48 40 778
service: aggregate perf. data 0.81 24516 97
service: refresh index statistics 0.03 196466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2022 16:39:21 to 13-08-2022 16:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5724
TEST_TIME 2 3239
TEST_TIME 3 3966
TEST_TIME 4 4256
TEST_TIME 5 5031
TEST_TIME 6 5034
TEST_TIME 7 4891
TEST_TIME 8 4662
TEST_TIME 9 5025
TEST_TIME 10 4941
TEST_TIME 11 5234
TEST_TIME 12 5507
TEST_TIME 13 5754
TEST_TIME 14 5799
TEST_TIME 15 6465
TEST_TIME 16 6600
TEST_TIME 17 7017
TEST_TIME 18 7059
TEST_TIME 19 7192
TEST_TIME 20 7602
TEST_TIME 21 7825
TEST_TIME 22 8093
TEST_TIME 23 8024
TEST_TIME 24 8131
TEST_TIME 25 8457
TEST_TIME 26 8535
TEST_TIME 27 8972
TEST_TIME 28 8952
TEST_TIME 29 9356
TEST_TIME 30 9309
TEST_TIME 31 9298
TEST_TIME 32 9753
TEST_TIME 33 9983
TEST_TIME 34 9878
TEST_TIME 35 9684
TEST_TIME 36 9756
TEST_TIME 37 9642
TEST_TIME 38 9546
TEST_TIME 39 9435
TEST_TIME 40 9367
TEST_TIME 41 9306
TEST_TIME 42 9267
TEST_TIME 43 9221
TEST_TIME 44 9140
TEST_TIME 45 9225
TEST_TIME 46 9110
TEST_TIME 47 9088
TEST_TIME 48 9025
TEST_TIME 49 9087
TEST_TIME 50 9289
TEST_TIME 51 9197
TEST_TIME 52 9128
TEST_TIME 53 9026
TEST_TIME 54 8906
TEST_TIME 55 8833
TEST_TIME 56 8793
TEST_TIME 57 8771
TEST_TIME 58 8884
TEST_TIME 59 8850
TEST_TIME 60 8884
TEST_TIME 61 8867
TEST_TIME 62 8964
TEST_TIME 63 8976
TEST_TIME 64 9015
TEST_TIME 65 9154
TEST_TIME 66 9162
TEST_TIME 67 9168
TEST_TIME 68 9186
TEST_TIME 69 9210
TEST_TIME 70 9278
TEST_TIME 71 9256
TEST_TIME 72 9263
TEST_TIME 73 9310
TEST_TIME 74 9212
TEST_TIME 75 9192
TEST_TIME 76 9198
TEST_TIME 77 9124
TEST_TIME 78 9140
TEST_TIME 79 9100
TEST_TIME 80 9084
TEST_TIME 81 9023
TEST_TIME 82 8955
TEST_TIME 83 8962
TEST_TIME 84 9026
TEST_TIME 85 9099
TEST_TIME 86 9059
TEST_TIME 87 9158
TEST_TIME 88 9129
TEST_TIME 89 9185
TEST_TIME 90 9226
TEST_TIME 91 9184
TEST_TIME 92 9206
TEST_TIME 93 9244
TEST_TIME 94 9235
TEST_TIME 95 9283
TEST_TIME 96 9362
TEST_TIME 97 9346
TEST_TIME 98 9291
TEST_TIME 99 9237
TEST_TIME 100 9240
TEST_TIME 101 9225
TEST_TIME 102 9248
TEST_TIME 103 9221
TEST_TIME 104 9204
TEST_TIME 105 9191
TEST_TIME 106 9169
TEST_TIME 107 9121
TEST_TIME 108 9152
TEST_TIME 109 9128
TEST_TIME 110 9144
TEST_TIME 111 9125
TEST_TIME 112 9119
TEST_TIME 113 9160
TEST_TIME 114 9134
TEST_TIME 115 9207
TEST_TIME 116 9195
TEST_TIME 117 9267
TEST_TIME 118 9266
TEST_TIME 119 9230
TEST_TIME 120 9202

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2022 16:39:25 to 13-08-2022 16:39:28.

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 1531587 1531587 0 0.00 0 24065 7 0 0
doc_list_biud 1531587 1531587 0 0.00 0 42947 28 0 0
sp_add_invoice_to_stock 5171 5064 107 2.06 26 177045 18602 0 107
sp_cancel_adding_invoice 5680 5680 0 0.00 3 64487 783 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 12874 395 0 0
sp_cancel_customer_reserve 555298 555298 0 0.00 0 22483 61 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 9546 109 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 2 2099 59 0 0
sp_cancel_supplier_invoice 6235 6225 10 0.16 1 27980 732 0 10
sp_cancel_supplier_order 432 432 0 0.00 10 970075 96834 0 0
sp_cancel_write_off 10002 10002 0 0.00 1 10987 114 0 0
sp_client_order 20736 20736 0 0.00 3 20074 401 0 0
sp_customer_reserve 462028 462028 0 0.00 0 99396 187 0 0
sp_fill_shopping_cart 31542 31542 0 0.00 1 41009 38 0 0
sp_fill_shopping_cart_clo_res 462020 462020 0 0.00 0 20460 15 0 0
sp_get_clo_for_invoice 5054 5054 0 0.00 0 34241 300 0 0
sp_kill_cost_storno 16721 16721 0 0.00 0 17332 55 0 0
sp_kill_qstorno_ret_qs2qd 1127255 1127255 0 0.00 0 25038 32 0 0
sp_kill_qty_storno 594469 594469 0 0.00 0 42944 70 0 0
sp_lock_selected_doc 20774 20774 0 0.00 0 9515 7 0 0
sp_make_cost_storno 28220 28220 0 0.00 0 24063 71 0 0
sp_multiply_rows_for_pdistr 28220 28220 0 0.00 0 14292 32 0 0
sp_multiply_rows_for_qdistr 20736 20736 0 0.00 0 19771 169 0 0
sp_pay_from_customer 7084 7084 0 0.00 3 16162 139 0 0
sp_pay_to_supplier 6038 6038 0 0.00 4 16022 160 0 0
sp_payment_common 13122 13122 0 0.00 1 16158 41 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 10701 348 0 0
sp_qd_handle_on_invoice_upd_sts 10734 10734 0 0.00 0 42936 336 0 0
sp_qd_handle_on_reserve_upd_sts 10044 10044 0 0.00 0 15792 63 0 0
sp_reserve_write_off 10044 10044 0 0.00 5 25093 210 0 0
sp_supplier_invoice 5422 5422 0 0.00 6 89591 3339 0 0
sp_supplier_order 5392 5392 0 0.00 5 26782 1290 0 0
srv_aggregate_perf_data 97 97 0 0.00 2650 438476 24517 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1430 103233 43426 0 0
srv_find_qd_qs_mism 1055952 1055952 0 0.00 0 17176 6 0 0
srv_make_invnt_saldo 523 523 0 0.00 37 74403 1239 0 0
srv_make_money_saldo 778 778 0 0.00 1 12823 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 125141 287005 196467 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 0 22262 178 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 5915 129 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 7179 24 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 2725 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 343 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 2795 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 3375 35 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 24022 1440 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 7262 16 0 0
t$perf-norm:sp_client_order 20736 20736 0 0.00 0 21579 18 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 5433 15 0 0
t$perf-norm:sp_pay_from_customer 7084 7084 0 0.00 0 10288 10 0 0
t$perf-norm:sp_pay_to_supplier 6038 6038 0 0.00 0 6762 9 0 0
t$perf-norm:sp_reserve_write_off 10044 10044 0 0.00 0 21576 14 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 15784 20 0 0
t$perf-norm:sp_supplier_order 5392 5392 0 0.00 0 20189 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 371 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 2670 24 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1968 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2763 692 0 0
v_add_invoice_to_stock 10320 10106 214 2.07 0 8029 19 0 214
v_all_customers 37560 37560 0 0.00 0 30 1 0 0
v_all_suppliers 30436 30436 0 0.00 0 27 1 0 0
v_all_wares 41472 41472 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3319 12 0 0
v_cancel_client_order 3468 3468 0 0.00 0 5181 7 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 4579 10 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 2256 6 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 2083 10 0 0
v_cancel_supplier_invoice 790 770 20 2.53 0 1928 13 0 20
v_cancel_supplier_order 864 864 0 0.00 0 2247 7 0 0
v_cancel_write_off 1936 1936 0 0.00 0 8437 18 0 0
v_max_id_clo_ord 5385 5385 0 0.00 0 269 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 3306 19 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 8759 4 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 14589 13 0 0
v_max_non_paid_realizn 3760 3760 0 0.00 0 6084 9 0 0
v_min_id_clo_ord 5385 5385 0 0.00 0 5177 8 0 0
v_min_id_clo_res 979 979 0 0.00 0 4806 7 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 4096 6 0 0
v_min_non_paid_invoice 6038 6038 0 0.00 0 15819 26 0 0
v_min_non_paid_realizn 7084 7084 0 0.00 0 12915 27 0 0
v_our_firm 10560 10560 0 0.00 0 30 1 0 0
v_reserve_write_off 20088 20088 0 0.00 0 10089 9 0 0
x_lock_depdocs_on_canc_invoice 11520 11520 0 0.00 0 21882 123 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 7742 321 0 0
x_make_qstorno_1000_1200 5392 5392 0 0.00 2 26756 1213 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 3 89495 3203 0 0
x_make_qstorno_2100_3300 462028 462028 0 0.00 0 99394 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2022 16:39:29 to 13-08-2022 16:39:33.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:32 31779 8192000000 9903796624 10216603648 986712320 10.750 100 87 174 0 1015916208 6532336 22671552
14:11:11 20012 8192000000 9961602640 10289188864 1043106400 11.295 100 87 174 0 1074650992 9096368 22448224
14:12:36 5363 8192000000 9989283680 10321162240 1072639120 11.577 100 91 182 0 1105268912 10335664 22294128
14:13:53 11149 8192000000 10024216640 10368159744 1110188976 11.934 100 86 172 0 1142580336 11699648 20691712
14:15:07 4556 8192000000 10068380448 10422927360 1155486192 12.361 100 86 168 0 1187400896 13127888 18786816
14:16:15 4422 8192000000 10112231696 10481254400 1206118352 12.833 100 69 138 0 1233560560 12178112 15264096
14:17:22 2504 8192000000 10157370112 10530140160 1245712848 13.199 100 86 171 1 1279201520 15017376 18471296
14:18:26 1899 8192000000 10189205264 10570162176 1275372800 13.471 100 81 160 1 1306014848 12699584 17942464
14:19:30 1359 8192000000 10208514032 10595794944 1294836304 13.648 100 76 153 0 1323862784 11281616 17744864
14:20:33 37293 8192000000 10193537184 10601779200 1306026800 13.750 100 36 72 0 1321010896 5596880 9387216
14:22:10 6348 8192000000 10206400464 10615037952 1315895504 13.840 100 34 68 2 1329357632 5463296 7998832
14:23:18 6615 8192000000 10207058032 10627088384 1326016608 13.931 100 21 40 1 1337091872 5955504 5119760
14:24:25 10862 8192000000 10213620608 10633756672 1331446928 13.980 100 11 23 1 1339348992 5298448 2603616
14:25:37 10446 8192000000 10229424256 10647351296 1337937984 14.039 100 30 61 1 1350912752 6046848 6927920
14:26:55 8360 8192000000 10252070560 10656899072 1340494304 14.062 100 74 150 0 1365217856 6064784 18658768
14:28:04 5025 8192000000 10236394080 10654756864 1340641200 14.063 100 32 62 0 1353446944 5725888 7079856
14:29:09 7881 8192000000 10205121120 10621186048 1300923968 13.704 98 15 31 0 1310305168 6040416 3340784
14:30:18 29749 8192000000 10163729728 10568675328 1219207360 12.954 94 39 76 0 1233646720 5114288 9325072
14:31:52 7099 8192000000 10141301584 10536202240 1223538928 12.994 97 22 44 0 1234695056 6194688 4961440
14:33:00 9880 8192000000 10132483344 10531201024 1221413568 12.975 98 13 26 1 1229443664 5176128 2853968
14:34:12 54900 8192000000 10167701856 10574811136 1265217024 13.378 99 42 85 0 1281544576 6180896 10146656
14:36:08 4919 8192000000 10185887392 10592800768 1294094320 13.642 100 40 80 0 1310425152 7286672 9044160
14:37:14 10517 8192000000 10194607568 10612375552 1309145520 13.778 100 22 42 1 1319517936 5409488 4962928
14:38:25 6874 8192000000 10227193632 10640863232 1330120816 13.968 100 19 38 0 1340149360 5648592 4379952
14:39:41 55948 8192000000 10256502752 10674565120 1353317232 14.177 100 45 88 2 1371647872 8316768 10013872
14:41:49 23412 8192000000 10259819248 10683109376 1359508784 14.233 100 28 55 0 1374101968 8429504 6163680
14:43:17 19075 8192000000 10279943408 10704355328 1374977904 14.372 100 45 90 0 1395193152 9948816 10266432
14:44:48 10886 8192000000 10306889040 10732355584 1394764832 14.548 100 51 103 0 1419783856 13109744 11909280
14:46:05 13350 8192000000 10317105504 10740707328 1402256048 14.615 100 56 110 2 1431498800 16240320 13002432
14:47:24 16701 8192000000 10337253504 10761555968 1412486352 14.706 100 55 110 0 1447898480 21348240 14063888
14:48:46 15032 8192000000 10332638864 10767261696 1414308432 14.722 100 41 80 1 1450739184 26024464 10406288
14:50:10 6468 8192000000 10343851184 10774245376 1420067296 14.773 100 62 121 1 1455127072 21129344 13930432
14:51:28 4417 8192000000 10353323088 10783625216 1428517920 14.848 100 65 130 1 1465988128 21212080 16258128
14:52:40 2467 8192000000 10335484368 10764312576 1408686656 14.672 100 56 111 0 1458189808 35695824 13807328
14:53:47 1429 8192000000 10353720576 10782498816 1413355200 14.714 100 77 151 2 1466663216 34795344 18512672
14:54:53 11770 8192000000 10329237776 10748616704 1376072704 14.381 99 67 132 0 1426909152 34997072 15839376
14:56:12 2080 8192000000 10337727376 10756313088 1394530368 14.546 100 60 121 0 1442220416 33197200 14492848
14:57:25 6008 8192000000 10312712784 10727735296 1365748960 14.289 98 60 121 0 1418486144 36914224 15822960
14:58:35 9350 8192000000 10319111344 10747211776 1383381424 14.447 100 43 85 0 1426248320 31853808 11013088
14:59:52 9521 8192000000 10302101696 10728456192 1371636992 14.342 100 34 68 0 1412945808 31057408 10251408
15:01:03 10119 8192000000 10300394144 10728431616 1370403184 14.331 100 34 68 1 1411525888 32578848 8543856
15:02:22 10078 8192000000 10310812544 10729545728 1356132896 14.203 98 47 95 0 1408272240 39804480 12334864
15:03:36 17129 8192000000 10304511696 10733723648 1336747552 14.028 97 32 64 0 1387985984 41902240 9336192
15:05:01 21699 8192000000 10307106784 10743042048 1363872800 14.272 99 35 68 0 1412114096 38872800 9368496
15:06:25 17373 8192000000 10301709936 10730016768 1344580864 14.099 98 21 42 0 1386089664 35599408 5909392
15:07:45 35643 8192000000 10289941712 10723885056 1361161584 14.248 100 29 58 0 1398307424 28995696 8150144
15:09:42 6424 8192000000 10298528048 10733379584 1370818128 14.334 100 38 76 0 1404861552 24744528 9298896
15:10:52 24845 8192000000 10258886624 10703761408 1353921312 14.183 100 9 18 0 1371437584 15608848 1907424
15:12:19 75905 8192000000 10283490352 10718306304 1357421536 14.214 99 22 41 1 1372593200 10317552 4854112
15:14:45 70949 8192000000 10261079952 10697089024 1312372800 13.808 96 15 28 0 1324659824 9598544 2688480
15:16:59 78836 8192000000 10272736096 10716745728 1365670528 14.288 100 16 32 0 1380410944 10867872 3872544
15:19:28 55214 8192000000 10256665056 10706796544 1339023184 14.049 98 31 61 0 1355012048 8744432 7244432
15:21:55 68023 8192000000 10289445984 10735853568 1389151984 14.498 100 32 65 0 1407838784 11236736 7450064
15:24:34 30205 8192000000 10280109408 10723581952 1369799584 14.325 99 26 52 0 1385169456 9593328 5776544
15:26:05 10274 8192000000 10308395872 10751860736 1397964912 14.577 100 31 61 1 1416496000 10996768 7534320
15:27:16 47307 8192000000 10302453024 10761740288 1401666768 14.610 100 19 38 0 1423580704 16742272 5171664
15:30:13 93967 8192000000 10286071920 10748235776 1392909504 14.532 100 16 32 0 1403809712 7016064 3884144
15:32:50 55591 8192000000 10301392144 10759225344 1390240800 14.508 99 17 34 0 1402046784 8042816 3763168
15:34:46 45331 8192000000 10282812784 10735456256 1337466080 14.035 95 14 29 1 1355795920 14774432 3555408
15:36:32 50554 8192000000 10286015456 10751152128 1392210176 14.526 100 4 8 0 1402740880 9675008 855696
15:38:35 50018 8192000000 10310760752 10767265792 1405925968 14.648 100 17 34 0 1416977232 7562352 3488912
15:40:25 91142 8192000000 10292355424 10747736064 1345640608 14.108 96 13 24 0 1366156512 17786960 2728944
15:42:57 41337 8192000000 10287758816 10755362816 1363079312 14.265 97 38 76 0 1384453824 12649936 8724576
15:44:39 49398 8192000000 10289893200 10723557376 1337394112 14.034 97 31 60 0 1354421680 10409824 6617744
15:46:29 32069 8192000000 10279632704 10717360128 1309329056 13.780 95 33 66 0 1326609120 10364976 6915088
15:48:12 86209 8192000000 10256423216 10696122368 1286909728 13.576 94 10 20 0 1301495504 12412912 2172864
15:50:41 51351 8192000000 10247198288 10693758976 1291429488 13.617 96 12 24 0 1303901840 9831376 2640976
15:52:35 43984 8192000000 10249992960 10687086592 1345762816 14.109 100 48 97 0 1363241984 6167024 11312144
15:54:23 67340 8192000000 10254141024 10698645504 1329455440 13.962 98 27 52 0 1347673504 12490144 5727920
15:56:35 40816 8192000000 10221068592 10657779712 1288205440 13.588 96 33 66 0 1304222800 9046240 6971120
15:58:16 65899 8192000000 10238589760 10686672896 1308869936 13.776 97 14 28 0 1323433344 11063280 3500128
16:00:28 91837 8192000000 10260351216 10713051136 1355610560 14.198 100 16 32 0 1377647184 17942624 4094000
16:03:02 94268 8192000000 10256520800 10691993600 1280754608 13.520 94 19 39 0 1301775840 16668160 4353072
16:05:41 78461 8192000000 10269596064 10714521600 1334918528 14.012 98 27 54 0 1356502064 15183776 6399760
16:08:06 69833 8192000000 10290083904 10713423872 1327002576 13.940 97 21 42 0 1341507744 9770160 4735008
16:10:16 89076 8192000000 10290452624 10743042048 1379986928 14.416 100 17 34 0 1397244672 13659808 3597936
16:12:46 56629 8192000000 10305444960 10764038144 1382308208 14.437 99 27 54 0 1400117392 11864368 5944816
16:14:52 103233 8192000000 10299318848 10760019968 1383560544 14.448 99 10 20 0 1396316080 10855776 1899760
16:17:36 99816 8192000000 10312287104 10774618112 1397585744 14.573 100 23 46 1 1418450320 15235696 5628880
16:20:23 48125 8192000000 10300881520 10770472960 1390696640 14.512 99 16 32 0 1409056784 15063072 3297072
16:22:11 96394 8192000000 10271027312 10737545216 1357681760 14.217 97 9 18 0 1367713408 8099728 1931920
16:24:53 52858 8192000000 10279647760 10741231616 1354493776 14.188 97 17 34 2 1368896176 10634032 3768368
16:26:46 63431 8192000000 10285762352 10752733184 1363305568 14.267 98 13 27 0 1379413808 13193648 2914592
16:28:52 51424 8192000000 10305256176 10757545984 1372591664 14.350 98 27 54 0 1391739744 13126016 6022064
16:30:43 48209 8192000000 10295562544 10749579264 1373269760 14.356 99 28 54 0 1395971328 16471920 6229648
16:32:38 50262 8192000000 10268592000 10715889664 1315433376 13.835 96 14 28 0 1334190576 15386240 3370960
16:34:39 16161 8192000000 10277201648 10713137152 1332167776 13.987 97 42 82 2 1351822896 9675824 9979296
16:35:59 77423 8192000000 10258239024 10705047552 1285088672 13.559 94 16 32 0 1299293568 10930720 3274176
16:38:26 18661 8192000000 8989844432 9179242496 154286992 1.848 12 2 3 0 159249280 4531488 430800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2022 16:39:35 to 13-08-2022 16:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2022 16:39:51 to 13-08-2022 16:39:53.

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          237404
MON$OLDEST_ACTIVE               237405
MON$OLDEST_SNAPSHOT             237405
MON$NEXT_TRANSACTION            237405
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                       2007552
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:182: 
:183: mscs-test-161.budzdorov.ru    Sat Aug 13 16:38:53 2022
:184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:185: 
:186: 
:187: mscs-test-161.budzdorov.ru    Sat Aug 13 16:38:53 2022
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:189: 
--- end of diff output ---

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

13.08.22 16:39:54. 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-08-13 16:39:56 No severe PSQL-related problems occured
13.08.22 16:39:57. End of report.