Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.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 15008804 kB
MemAvailable 30075812 kB
Buffers 0 kB
Cached 16676776 kB
SwapCached 3380 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-12-21 16:38:32
DTS_END                         2022-12-21 16:38:32.6560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43074
TRN_ID                          248305
ATT_ID                          698
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9606.56 16304 1152787
customer order: creation 185.57 345 22268
customer order: refuse 15.10 330 1812
order to supplier: creation 49.44 1117 5933
order to supplier: removal 3.64 100954 437
invoice (draft): creation 49.03 2813 5884
invoice (draft): removal 55.23 722 6628
invoice accept: apply 44.02 16659 5282
invoice accept: cancel 50.35 715 6042
customer reserve: creation 3961.85 170 475422
customer reserve: removal 4871.06 60 584527
realization accept: apply 90.08 156 10810
realization accept: cancel 96.26 104 11551
payment from customer: creation 61.62 104 7394
payment from customer: removal 6.20 73 744
payment to supplier: creation 51.31 118 6157
payment to supplier: removal 3.43 68 412
service: total inventory turnovers 5.13 1009 615
service: total monetary turnovers 6.27 28 752
service: aggregate perf. data 0.94 18035 113
service: refresh index statistics 0.03 182499 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-12-2022 16:39:19 to 21-12-2022 16:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7002
TEST_TIME 2 5777
TEST_TIME 3 5920
TEST_TIME 4 5765
TEST_TIME 5 5643
TEST_TIME 6 5176
TEST_TIME 7 5416
TEST_TIME 8 5319
TEST_TIME 9 5557
TEST_TIME 10 5530
TEST_TIME 11 5781
TEST_TIME 12 6107
TEST_TIME 13 6270
TEST_TIME 14 6372
TEST_TIME 15 6878
TEST_TIME 16 7209
TEST_TIME 17 7110
TEST_TIME 18 7279
TEST_TIME 19 7553
TEST_TIME 20 8741
TEST_TIME 21 8984
TEST_TIME 22 9233
TEST_TIME 23 9097
TEST_TIME 24 9584
TEST_TIME 25 10712
TEST_TIME 26 11075
TEST_TIME 27 11281
TEST_TIME 28 11212
TEST_TIME 29 10989
TEST_TIME 30 10765
TEST_TIME 31 10537
TEST_TIME 32 10611
TEST_TIME 33 10612
TEST_TIME 34 10443
TEST_TIME 35 10628
TEST_TIME 36 10436
TEST_TIME 37 10289
TEST_TIME 38 10145
TEST_TIME 39 10084
TEST_TIME 40 10237
TEST_TIME 41 10400
TEST_TIME 42 10328
TEST_TIME 43 10319
TEST_TIME 44 10115
TEST_TIME 45 10157
TEST_TIME 46 10148
TEST_TIME 47 10140
TEST_TIME 48 10065
TEST_TIME 49 10123
TEST_TIME 50 10230
TEST_TIME 51 10238
TEST_TIME 52 10265
TEST_TIME 53 10169
TEST_TIME 54 10223
TEST_TIME 55 10260
TEST_TIME 56 10231
TEST_TIME 57 10256
TEST_TIME 58 10236
TEST_TIME 59 10107
TEST_TIME 60 10029
TEST_TIME 61 9960
TEST_TIME 62 9874
TEST_TIME 63 9907
TEST_TIME 64 9875
TEST_TIME 65 9780
TEST_TIME 66 9765
TEST_TIME 67 9746
TEST_TIME 68 9698
TEST_TIME 69 9673
TEST_TIME 70 9727
TEST_TIME 71 9696
TEST_TIME 72 9755
TEST_TIME 73 9754
TEST_TIME 74 9801
TEST_TIME 75 9848
TEST_TIME 76 9915
TEST_TIME 77 9956
TEST_TIME 78 9957
TEST_TIME 79 9909
TEST_TIME 80 9829
TEST_TIME 81 9730
TEST_TIME 82 9770
TEST_TIME 83 9749
TEST_TIME 84 9676
TEST_TIME 85 9652
TEST_TIME 86 9621
TEST_TIME 87 9684
TEST_TIME 88 9709
TEST_TIME 89 9678
TEST_TIME 90 9683
TEST_TIME 91 9665
TEST_TIME 92 9707
TEST_TIME 93 9752
TEST_TIME 94 9790
TEST_TIME 95 9751
TEST_TIME 96 9754
TEST_TIME 97 9856
TEST_TIME 98 9841
TEST_TIME 99 9853
TEST_TIME 100 9843
TEST_TIME 101 9881
TEST_TIME 102 9800
TEST_TIME 103 9773
TEST_TIME 104 9717
TEST_TIME 105 9706
TEST_TIME 106 9667
TEST_TIME 107 9653
TEST_TIME 108 9589
TEST_TIME 109 9538
TEST_TIME 110 9627
TEST_TIME 111 9621
TEST_TIME 112 9602
TEST_TIME 113 9567
TEST_TIME 114 9645
TEST_TIME 115 9646
TEST_TIME 116 9624
TEST_TIME 117 9632
TEST_TIME 118 9650
TEST_TIME 119 9644
TEST_TIME 120 9617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-12-2022 16:39:23 to 21-12-2022 16:39:27.

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 1596109 1596109 0 0.00 0 24404 6 0 0
doc_list_biud 1596107 1596107 0 0.00 0 26573 27 0 0
sp_add_invoice_to_stock 5368 5282 86 1.60 45 166439 16659 0 86
sp_cancel_adding_invoice 6042 6042 0 0.00 4 73347 716 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 10109 331 0 0
sp_cancel_customer_reserve 584527 584527 0 0.00 0 24894 60 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 3 6868 73 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 5419 69 0 0
sp_cancel_supplier_invoice 6634 6628 6 0.09 1 20441 722 0 6
sp_cancel_supplier_order 437 437 0 0.00 8 1240711 100954 0 0
sp_cancel_write_off 11551 11551 0 0.00 1 10549 104 0 0
sp_client_order 22268 22268 0 0.00 3 14199 345 0 0
sp_customer_reserve 475422 475422 0 0.00 0 59934 171 0 0
sp_fill_shopping_cart 34078 34078 0 0.00 1 22401 33 0 0
sp_fill_shopping_cart_clo_res 475408 475408 0 0.00 0 10446 13 0 0
sp_get_clo_for_invoice 5260 5260 0 0.00 0 23168 261 0 0
sp_kill_cost_storno 18749 18749 0 0.00 0 24392 49 0 0
sp_kill_qstorno_ret_qs2qd 1187670 1187670 0 0.00 0 24048 32 0 0
sp_kill_qty_storno 627064 627064 0 0.00 0 26570 69 0 0
sp_lock_selected_doc 22134 22134 0 0.00 0 7039 5 0 0
sp_make_cost_storno 29620 29620 0 0.00 0 10779 48 0 0
sp_multiply_rows_for_pdistr 29618 29618 0 0.00 0 6410 24 0 0
sp_multiply_rows_for_qdistr 22268 22268 0 0.00 0 10564 154 0 0
sp_pay_from_customer 7394 7394 0 0.00 4 16617 104 0 0
sp_pay_to_supplier 6157 6157 0 0.00 4 12335 118 0 0
sp_payment_common 13551 13551 0 0.00 1 16585 26 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 10089 302 0 0
sp_qd_handle_on_invoice_upd_sts 11300 11300 0 0.00 0 26565 300 0 0
sp_qd_handle_on_reserve_upd_sts 10809 10809 0 0.00 0 14444 52 0 0
sp_reserve_write_off 10810 10810 0 0.00 6 14540 156 0 0
sp_supplier_invoice 5884 5884 0 0.00 6 102537 2814 0 0
sp_supplier_order 5933 5933 0 0.00 5 19886 1118 0 0
srv_aggregate_perf_data 113 113 0 0.00 2587 225667 18035 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1211 187991 43762 0 0
srv_find_qd_qs_mism 1104237 1104237 0 0.00 0 10976 6 0 0
srv_make_invnt_saldo 615 615 0 0.00 34 63137 1010 0 0
srv_make_money_saldo 752 752 0 0.00 1 3295 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 122414 251360 182499 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5282 5282 0 0.00 0 24280 182 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 2814 70 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 13034 23 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 2191 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 1690 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 5423 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 585 13 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 23260 1249 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 1373 6 0 0
t$perf-norm:sp_client_order 22268 22268 0 0.00 0 8942 18 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 8672 19 0 0
t$perf-norm:sp_pay_from_customer 7394 7394 0 0.00 0 13332 13 0 0
t$perf-norm:sp_pay_to_supplier 6157 6157 0 0.00 0 20869 15 0 0
t$perf-norm:sp_reserve_write_off 10810 10810 0 0.00 0 10121 11 0 0
t$perf-norm:sp_supplier_invoice 5884 5884 0 0.00 0 6006 17 0 0
t$perf-norm:sp_supplier_order 5933 5933 0 0.00 0 20858 20 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 238 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 615 615 0 0.00 0 686 17 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 4405 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10684 10512 172 1.60 0 5929 17 0 172
v_all_customers 40064 40064 0 0.00 0 23 0 0 0
v_all_suppliers 32518 32518 0 0.00 0 19 1 0 0
v_all_wares 44536 44536 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1443 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 5935 6 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 2484 6 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 2779 7 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 352 3 0 0
v_cancel_supplier_invoice 832 820 12 1.44 0 2317 18 0 12
v_cancel_supplier_order 874 874 0 0.00 0 294 4 0 0
v_cancel_write_off 2156 2156 0 0.00 0 3445 15 0 0
v_max_id_clo_ord 5933 5933 0 0.00 0 1977 1 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 8058 28 0 0
v_max_id_ord_sup 5877 5877 0 0.00 0 7143 5 0 0
v_max_non_paid_invoice 1708 1708 0 0.00 0 148 2 0 0
v_max_non_paid_realizn 4037 4037 0 0.00 0 7043 6 0 0
v_min_id_clo_ord 5933 5933 0 0.00 0 3062 6 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 1622 5 0 0
v_min_id_ord_sup 5877 5877 0 0.00 0 6643 5 0 0
v_min_non_paid_invoice 6157 6157 0 0.00 0 6540 17 0 0
v_min_non_paid_realizn 7394 7394 0 0.00 0 16574 20 0 0
v_our_firm 11186 11186 0 0.00 0 25 1 0 0
v_reserve_write_off 21618 21618 0 0.00 0 5935 6 0 0
x_lock_depdocs_on_canc_invoice 12260 12260 0 0.00 0 11182 97 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 11560 337 0 0
x_make_qstorno_1000_1200 5933 5933 0 0.00 1 19877 1052 0 0
x_make_qstorno_1200_2000 5884 5884 0 0.00 3 100218 2689 0 0
x_make_qstorno_2100_3300 475422 475422 0 0.00 0 59933 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2022 16:39:28 to 21-12-2022 16:39:32.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:38 21570 8192000000 9894827344 10203115520 972745776 10.613 100 86 172 0 1001316736 6097232 22473728
14:11:06 11015 8192000000 9933366160 10251911168 1017401936 11.047 100 89 176 1 1049070448 8111024 23557488
14:12:23 13527 8192000000 9974309632 10300329984 1055683088 11.415 100 88 176 0 1087584992 9678752 22223152
14:13:43 11788 8192000000 10031700448 10378629120 1114506944 11.975 100 71 142 1 1141548448 10052560 16988944
14:14:58 7559 8192000000 10081186384 10437419008 1171056864 12.507 100 85 169 0 1203155728 12877488 19221376
14:16:07 3917 8192000000 10121093888 10486992896 1208442768 12.855 100 82 162 0 1239421536 12562432 18416336
14:17:13 3260 8192000000 10151794960 10527838208 1244975536 13.192 100 84 166 0 1276083616 13176112 17931968
14:18:19 2923 8192000000 10177277392 10561048576 1270293152 13.424 100 73 145 1 1298520304 12653712 15573440
14:19:25 815 8192000000 10205814576 10592628736 1298679872 13.683 100 77 154 0 1328430592 12653088 17097632
14:20:29 1413 8192000000 10213843408 10611904512 1308404880 13.772 100 65 126 0 1335310224 13247472 13657872
14:21:32 2899 8192000000 10224372816 10627006464 1321820080 13.893 100 43 83 1 1339975776 9042224 9113472
14:22:35 6554 8192000000 10219053536 10631323648 1329523968 13.963 100 31 62 0 1343456064 6930528 7001568
14:23:43 7821 8192000000 10220290640 10639687680 1338688784 14.046 100 19 38 0 1352381280 9625552 4066944
14:24:52 7795 8192000000 10242936960 10649382912 1341112128 14.067 100 57 112 1 1360412304 5546096 13754080
14:26:00 3221 8192000000 10234999184 10656198656 1343944336 14.093 100 30 58 0 1357502112 6883200 6674576
14:27:04 7033 8192000000 10232669120 10655350784 1331374752 13.980 99 16 30 1 1340736160 6057616 3303792
14:28:15 33810 8192000000 10207823072 10631389184 1282106992 13.532 96 21 42 0 1291382096 4974288 4300816
14:29:49 3995 8192000000 10199009568 10610741248 1310349280 13.789 100 30 56 0 1323279328 6486352 6443696
14:30:53 8490 8192000000 10112587744 10509094912 1163366272 12.435 92 20 40 0 1174425424 6042336 5016816
14:32:02 11256 8192000000 10148163696 10548436992 1251337952 13.251 99 34 65 0 1265245680 6172960 7734768
14:33:14 10074 8192000000 10151734352 10549489664 1256606192 13.299 100 41 83 0 1273489728 8335008 8548528
14:34:26 8409 8192000000 10161487600 10563493888 1259404864 13.325 99 25 51 0 1270780128 5657600 5717664
14:35:36 7957 8192000000 10187070592 10595250176 1294371952 13.644 100 21 42 0 1304838704 5728624 4738128
14:36:48 8829 8192000000 10191504688 10605432832 1302185472 13.715 100 19 39 0 1311923488 5285808 4452208
14:37:59 19742 8192000000 10200050192 10618093568 1314136304 13.824 100 18 34 2 1322393952 4377856 3879792
14:39:27 38600 8192000000 10253074576 10666340352 1342575120 14.081 100 48 95 0 1363375568 10729440 10071008
14:41:14 3012 8192000000 10278303200 10692677632 1370246928 14.329 100 58 116 2 1397970592 13741632 13982032
14:42:20 21725 8192000000 10290260224 10711515136 1378030752 14.399 100 50 99 0 1405810224 16020944 11758528
14:43:51 8251 8192000000 10308088128 10728779776 1388655312 14.494 100 52 101 1 1420512160 20523488 11333360
14:45:05 13609 8192000000 10318508832 10742308864 1396823056 14.567 100 42 82 0 1431825216 25300384 9701776
14:46:26 7886 8192000000 10337273360 10761826304 1413627024 14.716 100 49 95 1 1450546928 25057104 11862800
14:47:37 8090 8192000000 10322377984 10740244480 1378233296 14.401 98 57 113 0 1419097520 27247920 13616304
14:48:51 8153 8192000000 10349293312 10769108992 1411635024 14.698 100 60 120 1 1460490112 33096272 15758816
14:50:07 6411 8192000000 10352395744 10773032960 1415919504 14.737 100 56 111 0 1464935424 34350256 14665664
14:51:21 3094 8192000000 10376131760 10788990976 1423803808 14.806 100 77 154 0 1480826112 38167296 18855008
14:52:28 5063 8192000000 10381535952 10804445184 1432039264 14.879 100 62 121 0 1491009824 43175216 15795344
14:53:39 1564 8192000000 10380488496 10797281280 1427412448 14.838 100 74 146 0 1485985664 40810160 17763056
14:54:45 1500 8192000000 10391236688 10802941952 1413172544 14.712 99 80 159 0 1476510336 43057328 20280464
14:55:55 5626 8192000000 10393439120 10801246208 1418930928 14.763 100 74 149 0 1492026976 53883344 19212704
14:57:15 1209 8192000000 10385616496 10787647488 1395375888 14.554 99 90 178 3 1480788080 62608560 22803632
14:58:18 19230 8192000000 10355399664 10778521600 1385624144 14.467 99 41 82 1 1455884240 58774544 11485552
14:59:43 18592 8192000000 10332424144 10775334912 1388719216 14.494 100 28 56 0 1442027488 45391536 7916736
15:01:22 22480 8192000000 10319460288 10752942080 1339649856 14.054 98 32 65 0 1407688080 58730064 9308160
15:03:08 31633 8192000000 10293963312 10737139712 1341765088 14.073 99 28 53 3 1389919312 40564928 7589296
15:05:28 42822 8192000000 10238771232 10685526016 1282539328 13.536 96 8 16 0 1302320144 17867408 1913408
15:07:19 36346 8192000000 10246427616 10679181312 1312876736 13.812 98 27 53 1 1325163824 6438464 5848624
15:08:56 61691 8192000000 10279334688 10710818816 1360075632 14.238 100 45 88 2 1381080208 10494400 10510176
15:11:43 95671 8192000000 10294753184 10738589696 1350515264 14.152 98 19 37 0 1368277776 13704080 4058432
15:14:22 49314 8192000000 10294909744 10734874624 1370945840 14.336 99 16 32 0 1389478720 14446016 4086864
15:16:13 59343 8192000000 10321630768 10755936256 1386637824 14.476 100 22 44 0 1407244368 15153696 5452848
15:18:15 21796 8192000000 10305186432 10767245312 1379772480 14.415 99 23 47 0 1409687728 23712016 6203232
15:19:47 52328 8192000000 10302433472 10757640192 1399309488 14.589 100 8 16 0 1419040752 18131808 1599456
15:21:40 35500 8192000000 10305960096 10769911808 1402183072 14.614 100 12 24 0 1417917616 12968384 2766160
15:23:38 56258 8192000000 10314209904 10769821696 1391730688 14.521 99 8 16 0 1408155280 14155184 2269408
15:26:52 33265 8192000000 10313163936 10758045696 1391135824 14.516 99 28 55 1 1408918832 11614736 6168272
15:28:27 61659 8192000000 10308157824 10771525632 1387864256 14.487 99 12 25 0 1402029200 11722928 2442016
15:30:48 46789 8192000000 10320125776 10757357568 1359055120 14.229 97 39 78 0 1377922848 9728976 9138752
15:32:36 187974 8192000000 10302658064 10750078976 1315741936 13.838 94 21 40 0 1336487584 16514864 4230784
15:36:45 77631 8192000000 10284646976 10738282496 1377258720 14.392 100 27 54 0 1396958256 13913744 5785792
15:39:04 69570 8192000000 10268902256 10722889728 1352995136 14.174 98 21 43 0 1367215520 9803776 4416608
15:41:14 68064 8192000000 10261091648 10731470848 1351548928 14.161 98 11 23 0 1366092112 11719584 2823600
15:43:25 84076 8192000000 10236802368 10690113536 1310540560 13.791 96 17 34 0 1321465216 7116784 3807872
15:45:54 41761 8192000000 10248498112 10688147456 1316939248 13.849 97 18 36 0 1327276400 6679104 3658048
15:47:38 50016 8192000000 10244345024 10691964928 1317437120 13.853 98 24 48 0 1337433520 14392352 5604048
15:49:39 76755 8192000000 10264712544 10718744576 1342723776 14.082 99 20 40 0 1362292656 15137408 4431472
15:52:00 25250 8192000000 10268586336 10706558976 1344913488 14.102 100 32 64 0 1366889712 14708640 7267584
15:53:26 69853 8192000000 10274865248 10727776256 1329924400 13.966 98 19 38 0 1357391840 22858080 4609360
15:55:38 91475 8192000000 10276303776 10725625856 1348864656 14.137 98 15 31 1 1361494592 9440304 3189632
15:58:10 11154 8192000000 10296212784 10731184128 1376246288 14.383 100 61 123 0 1402440912 12065680 14128944
15:59:23 100128 8192000000 10276094144 10737905664 1378014944 14.399 100 33 64 0 1394015280 7753840 8246496
16:02:08 81589 8192000000 10291522944 10760249344 1386417200 14.474 100 10 20 0 1404718000 15642800 2658000
16:04:42 107051 8192000000 10276093856 10730024960 1359991200 14.237 98 22 44 0 1380722832 15948272 4783360
16:07:43 52067 8192000000 10277805984 10724896768 1335599600 14.018 97 19 38 0 1349203456 9694000 3909856
16:09:35 14036 8192000000 10296245296 10741903360 1377456256 14.394 99 32 65 0 1393121776 9045248 6620272
16:10:50 54862 8192000000 10287168288 10743611392 1363637792 14.270 98 9 19 0 1375989264 10267008 2084464
16:12:45 64407 8192000000 10293784624 10742181888 1351845152 14.164 97 13 24 0 1369880512 15612576 2422784
16:14:50 29478 8192000000 10323882496 10769117184 1395597488 14.556 100 39 78 0 1427332928 23142608 8592832
16:16:24 72312 8192000000 10304843728 10755518464 1368453424 14.313 98 10 20 0 1380493424 9877408 2162592
16:18:42 13746 8192000000 10315990544 10751078400 1391669328 14.521 100 46 88 1 1410983968 8093168 11221472
16:19:58 84624 8192000000 10294566368 10749644800 1377799680 14.397 99 13 26 0 1397249168 16276000 3173488
16:22:24 27638 8192000000 10309166576 10754625536 1388036048 14.488 99 43 84 2 1406925392 8224912 10664432
16:24:00 57847 8192000000 10288265296 10754891776 1376880992 14.389 99 10 21 0 1395506656 15934640 2691024
16:27:41 63721 8192000000 10249897312 10722107392 1325956352 13.931 98 12 24 0 1348324640 19314848 3053440
16:29:45 36064 8192000000 10287974544 10731745280 1357197632 14.212 99 41 80 1 1379044032 12422976 9423424
16:31:22 54030 8192000000 10256266048 10694782976 1294701952 13.647 95 29 58 1 1311938112 10057136 7179024
16:33:26 60510 8192000000 10251769952 10692198400 1305674016 13.747 97 14 28 0 1320329888 11719968 2935904
16:35:27 26061 8192000000 10261608048 10695819264 1320387024 13.880 98 33 66 0 1337514304 10208080 6919200
16:36:53 24697 8192000000 10248344112 10689757184 1316152000 13.842 98 26 52 0 1336004480 14160608 5691872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-12-2022 16:39:34 to 21-12-2022 16:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2022 16:39:50 to 21-12-2022 16:39:52.

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          244835
MON$OLDEST_ACTIVE               248485
MON$OLDEST_SNAPSHOT             248485
MON$NEXT_TRANSACTION            248485
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                       2027680
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:956: 
:957: mscs-test-161.budzdorov.ru    Wed Dec 21 16:38:35 2022
:958:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:959: 
:960: 
:961: mscs-test-161.budzdorov.ru    Wed Dec 21 16:38:35 2022
:962:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:963: 
:964: 
:965: mscs-test-161.budzdorov.ru    Wed Dec 21 16:38:39 2022
:966:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:967: 
--- end of diff output ---

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

21.12.22 16:39:53. 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-12-21 16:39:56 No severe PSQL-related problems occured
21.12.22 16:39:57. End of report.