Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.01.2023 19:40
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 15467564 kB
MemAvailable 30458212 kB
Buffers 0 kB
Cached 16268104 kB
SwapCached 3664 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 2023-01-30 19:38:35
DTS_END                         2023-01-30 19:38:35.1560
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/36636
TRN_ID                          240813
ATT_ID                          621
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: 9062.23 18962 1087468
customer order: creation 176.39 319 21167
customer order: refuse 14.48 335 1737
order to supplier: creation 45.33 1218 5439
order to supplier: removal 3.38 108470 406
invoice (draft): creation 46.88 3286 5626
invoice (draft): removal 50.78 828 6094
invoice accept: apply 41.60 17197 4992
invoice accept: cancel 45.77 752 5492
customer reserve: creation 3826.32 171 459158
customer reserve: removal 4503.77 64 540452
realization accept: apply 85.58 178 10270
realization accept: cancel 90.48 105 10857
payment from customer: creation 60.96 131 7315
payment from customer: removal 5.60 71 672
payment to supplier: creation 49.43 153 5931
payment to supplier: removal 3.40 59 408
service: total inventory turnovers 4.89 1209 587
service: total monetary turnovers 6.35 26 762
service: aggregate perf. data 0.83 22096 99
service: refresh index statistics 0.03 222570 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-01-2023 19:40:30 to 30-01-2023 19:40:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4475
TEST_TIME 2 3393
TEST_TIME 3 4298
TEST_TIME 4 4162
TEST_TIME 5 4640
TEST_TIME 6 4425
TEST_TIME 7 4560
TEST_TIME 8 5193
TEST_TIME 9 5113
TEST_TIME 10 5526
TEST_TIME 11 5821
TEST_TIME 12 5864
TEST_TIME 13 5852
TEST_TIME 14 6082
TEST_TIME 15 5996
TEST_TIME 16 6412
TEST_TIME 17 6808
TEST_TIME 18 6814
TEST_TIME 19 6985
TEST_TIME 20 7491
TEST_TIME 21 8065
TEST_TIME 22 8052
TEST_TIME 23 8452
TEST_TIME 24 8354
TEST_TIME 25 8385
TEST_TIME 26 8489
TEST_TIME 27 8345
TEST_TIME 28 9123
TEST_TIME 29 9982
TEST_TIME 30 10649
TEST_TIME 31 10604
TEST_TIME 32 10613
TEST_TIME 33 10359
TEST_TIME 34 10294
TEST_TIME 35 10095
TEST_TIME 36 10077
TEST_TIME 37 9922
TEST_TIME 38 9768
TEST_TIME 39 9699
TEST_TIME 40 9586
TEST_TIME 41 9488
TEST_TIME 42 9711
TEST_TIME 43 9580
TEST_TIME 44 9388
TEST_TIME 45 9439
TEST_TIME 46 9409
TEST_TIME 47 9376
TEST_TIME 48 9308
TEST_TIME 49 9296
TEST_TIME 50 9265
TEST_TIME 51 9181
TEST_TIME 52 9049
TEST_TIME 53 8887
TEST_TIME 54 8843
TEST_TIME 55 9045
TEST_TIME 56 9096
TEST_TIME 57 9118
TEST_TIME 58 9136
TEST_TIME 59 9112
TEST_TIME 60 9145
TEST_TIME 61 9144
TEST_TIME 62 9198
TEST_TIME 63 9195
TEST_TIME 64 9241
TEST_TIME 65 9225
TEST_TIME 66 9265
TEST_TIME 67 9275
TEST_TIME 68 9282
TEST_TIME 69 9292
TEST_TIME 70 9321
TEST_TIME 71 9223
TEST_TIME 72 9149
TEST_TIME 73 9138
TEST_TIME 74 9102
TEST_TIME 75 9106
TEST_TIME 76 9066
TEST_TIME 77 9011
TEST_TIME 78 8994
TEST_TIME 79 8896
TEST_TIME 80 8960
TEST_TIME 81 9015
TEST_TIME 82 9060
TEST_TIME 83 9063
TEST_TIME 84 9070
TEST_TIME 85 9092
TEST_TIME 86 9086
TEST_TIME 87 9067
TEST_TIME 88 9037
TEST_TIME 89 9116
TEST_TIME 90 9186
TEST_TIME 91 9166
TEST_TIME 92 9257
TEST_TIME 93 9274
TEST_TIME 94 9216
TEST_TIME 95 9141
TEST_TIME 96 9164
TEST_TIME 97 9168
TEST_TIME 98 9152
TEST_TIME 99 9114
TEST_TIME 100 9091
TEST_TIME 101 9078
TEST_TIME 102 9013
TEST_TIME 103 9008
TEST_TIME 104 8975
TEST_TIME 105 9064
TEST_TIME 106 9033
TEST_TIME 107 9085
TEST_TIME 108 9079
TEST_TIME 109 9099
TEST_TIME 110 9096
TEST_TIME 111 9096
TEST_TIME 112 9110
TEST_TIME 113 9128
TEST_TIME 114 9136
TEST_TIME 115 9148
TEST_TIME 116 9216
TEST_TIME 117 9190
TEST_TIME 118 9117
TEST_TIME 119 9101
TEST_TIME 120 9065

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-01-2023 19:40:34 to 30-01-2023 19:40:38.

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 1513002 1513002 0 0.00 0 40198 7 0 0
doc_list_biud 1513001 1513001 0 0.00 0 56835 28 0 0
sp_add_invoice_to_stock 5067 4992 75 1.48 28 159155 17198 0 75
sp_cancel_adding_invoice 5492 5492 0 0.00 4 57661 753 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 9796 335 0 0
sp_cancel_customer_reserve 540452 540452 0 0.00 0 56836 64 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 3854 71 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 2 2850 59 0 0
sp_cancel_supplier_invoice 6096 6094 2 0.03 1 16643 828 0 2
sp_cancel_supplier_order 406 406 0 0.00 6 1074580 108470 0 0
sp_cancel_write_off 10857 10857 0 0.00 1 21036 106 0 0
sp_client_order 21167 21167 0 0.00 3 30620 319 0 0
sp_customer_reserve 459158 459158 0 0.00 0 47477 172 0 0
sp_fill_shopping_cart 32229 32229 0 0.00 1 16296 41 0 0
sp_fill_shopping_cart_clo_res 459153 459153 0 0.00 0 14232 13 0 0
sp_get_clo_for_invoice 4987 4987 0 0.00 0 23215 321 0 0
sp_kill_cost_storno 17429 17429 0 0.00 0 20824 56 0 0
sp_kill_qstorno_ret_qs2qd 1098261 1098261 0 0.00 0 56834 35 0 0
sp_kill_qty_storno 580295 580295 0 0.00 0 56835 74 0 0
sp_lock_selected_doc 20959 20959 0 0.00 0 7999 6 0 0
sp_make_cost_storno 28503 28503 0 0.00 0 35926 70 0 0
sp_multiply_rows_for_pdistr 28503 28503 0 0.00 0 28643 26 0 0
sp_multiply_rows_for_qdistr 21167 21167 0 0.00 0 23643 125 0 0
sp_pay_from_customer 7315 7315 0 0.00 4 28519 132 0 0
sp_pay_to_supplier 5931 5931 0 0.00 4 37868 154 0 0
sp_payment_common 13246 13246 0 0.00 1 21290 48 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 9791 302 0 0
sp_qd_handle_on_invoice_upd_sts 10479 10479 0 0.00 0 39502 299 0 0
sp_qd_handle_on_reserve_upd_sts 10270 10270 0 0.00 0 11075 62 0 0
sp_reserve_write_off 10270 10270 0 0.00 6 40221 179 0 0
sp_supplier_invoice 5626 5626 0 0.00 7 76458 3287 0 0
sp_supplier_order 5439 5439 0 0.00 4 27991 1219 0 0
srv_aggregate_perf_data 99 99 0 0.00 3194 256345 22096 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1637 155438 46637 0 0
srv_find_qd_qs_mism 1039488 1039488 0 0.00 0 14400 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 38 117147 1210 0 0
srv_make_money_saldo 762 762 0 0.00 1 1390 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 179899 300146 222570 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4992 4992 0 0.00 0 11996 132 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 3186 69 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 5041 16 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 5726 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 3833 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 837 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1653 13 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 29218 1529 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1577 4 0 0
t$perf-norm:sp_client_order 21167 21167 0 0.00 0 10396 16 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 3404 8 0 0
t$perf-norm:sp_pay_from_customer 7315 7315 0 0.00 0 8686 13 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 22785 19 0 0
t$perf-norm:sp_reserve_write_off 10270 10270 0 0.00 0 7568 11 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 9851 19 0 0
t$perf-norm:sp_supplier_order 5439 5439 0 0.00 0 20569 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 326 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 791 13 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 3628 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10124 9974 150 1.48 0 2902 16 0 150
v_all_customers 38892 38892 0 0.00 0 24 0 0 0
v_all_suppliers 30752 30752 0 0.00 0 25 1 0 0
v_all_wares 42334 42334 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 416 6 0 0
v_cancel_client_order 3474 3474 0 0.00 0 2912 5 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 1259 6 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1586 6 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 339 3 0 0
v_cancel_supplier_invoice 832 828 4 0.48 0 1591 18 0 4
v_cancel_supplier_order 812 812 0 0.00 0 3136 9 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2604 14 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 681 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1336 8 0 0
v_max_id_ord_sup 5623 5623 0 0.00 0 3876 5 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 17086 13 0 0
v_max_non_paid_realizn 3692 3692 0 0.00 0 4072 5 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 4430 8 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 9447 22 0 0
v_min_id_ord_sup 5623 5623 0 0.00 0 5553 12 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 21021 30 0 0
v_min_non_paid_realizn 7315 7315 0 0.00 0 21142 47 0 0
v_our_firm 10688 10688 0 0.00 0 24 1 0 0
v_reserve_write_off 20540 20540 0 0.00 0 3595 5 0 0
x_lock_depdocs_on_canc_invoice 11172 11172 0 0.00 0 21062 135 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 6878 347 0 0
x_make_qstorno_1000_1200 5439 5439 0 0.00 1 27982 1146 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 2 76448 3126 0 0
x_make_qstorno_2100_3300 459158 459158 0 0.00 0 47449 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 19:40:39 to 30-01-2023 19:40:43.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:39 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:39 35214 8192000000 9909616464 10223255552 990243296 10.784 100 85 170 0 1018196016 6249536 21703184
17:11:17 11391 8192000000 9948106832 10270175232 1029914416 11.168 100 85 170 0 1060077648 8573168 21590064
17:12:30 10163 8192000000 9963170928 10295689216 1052799904 11.388 100 77 154 0 1082097968 9865504 19432560
17:13:46 8253 8192000000 10016524240 10360332288 1103297152 11.869 100 77 152 1 1132171312 10584000 18290160
17:14:56 5620 8192000000 10068765248 10422800384 1153560640 12.343 100 82 165 0 1184898336 11861824 19475872
17:16:06 3916 8192000000 10115762736 10476773376 1196246080 12.741 100 90 180 1 1231076464 14276512 20553872
17:17:13 1372 8192000000 10147237584 10517819392 1227833808 13.034 100 87 171 0 1260493744 13860640 18799296
17:18:17 2889 8192000000 10159760272 10534879232 1249488256 13.234 100 83 164 0 1281567712 13723904 18355552
17:19:21 1000 8192000000 10197268240 10581426176 1282158624 13.533 100 88 174 0 1313515472 11379520 19977328
17:20:24 45848 8192000000 10195566240 10595856384 1295266736 13.652 100 56 112 0 1317455824 9054528 13134560
17:22:11 6442 8192000000 10185364640 10596618240 1300297008 13.698 100 17 34 0 1310275552 5856064 4122480
17:23:19 3848 8192000000 10203617472 10613637120 1311326928 13.798 100 32 64 2 1323964384 6136560 6500896
17:24:27 8305 8192000000 10203718256 10620243968 1317684640 13.856 100 25 52 4 1328909344 5602096 5622608
17:25:40 65292 8192000000 10229535856 10641084416 1325028928 13.922 100 32 65 1 1342047344 9622880 7395536
17:27:46 5170 8192000000 10217097296 10635014144 1323822608 13.911 100 35 70 1 1339191584 7146032 8222944
17:28:55 6067 8192000000 10208556560 10630709248 1313482240 13.818 99 31 62 0 1326984608 6059520 7442848
17:30:05 56893 8192000000 10162818928 10562416640 1199041856 12.767 92 31 59 2 1210116768 4825344 6249568
17:32:02 5965 8192000000 10142715472 10538741760 1205092224 12.824 95 28 53 0 1216181024 4665728 6423072
17:33:10 11464 8192000000 10142101408 10544930816 1219744832 12.959 97 21 42 2 1230273968 5830592 4698544
17:34:22 14766 8192000000 10170326224 10577424384 1279718208 13.510 100 24 48 0 1291773520 5198208 6857104
17:35:40 14919 8192000000 10167941264 10581200896 1263614640 13.363 99 22 44 0 1274340416 5698800 5026976
17:36:56 4740 8192000000 10182221888 10596139008 1290443376 13.608 100 25 50 0 1302566512 6755584 5367552
17:38:01 7844 8192000000 10203827264 10616856576 1307999312 13.768 100 25 50 0 1318735744 4275328 6461104
17:39:15 56572 8192000000 10242966464 10657624064 1337217888 14.032 100 45 90 0 1354369696 7271328 9880480
17:41:16 18507 8192000000 10241318992 10662776832 1337470464 14.035 99 33 65 0 1355872512 11083856 7318192
17:42:40 8484 8192000000 10272664368 10688524288 1359698944 14.235 100 57 110 1 1388729360 16871680 12158736
17:44:05 15396 8192000000 10299718912 10719997952 1378309408 14.401 100 37 72 1 1401137520 14055712 8772400
17:45:25 19243 8192000000 10304449184 10732048384 1387993952 14.488 100 36 72 1 1419580448 22718848 8867648
17:46:52 30460 8192000000 10332260176 10759127040 1401662416 14.610 100 42 82 0 1434901360 23396912 9842032
17:48:36 4643 8192000000 10339506352 10763530240 1397113312 14.569 99 61 122 1 1444469376 32289536 15066528
17:49:46 22388 8192000000 10333264688 10768195584 1403351952 14.625 99 42 80 1 1440922576 26559696 11010928
17:51:22 10384 8192000000 10349822416 10786148352 1422315552 14.793 100 42 83 0 1460291088 27111248 10864288
17:52:41 3169 8192000000 10353651808 10779172864 1413414048 14.714 100 59 118 1 1463074224 34096256 15563920
17:53:53 8134 8192000000 10358542528 10782855168 1402848208 14.620 99 56 110 0 1459780928 42298400 14634320
17:55:07 4986 8192000000 10366120704 10781196288 1407639088 14.663 100 73 147 3 1471373152 44879152 18854912
17:56:17 6578 8192000000 10364633584 10781147136 1395149008 14.552 99 68 135 0 1458192480 46341632 16701840
17:57:29 4423 8192000000 10384625024 10801127424 1412743328 14.708 100 67 134 0 1487599136 56708064 18147744
17:58:39 10818 8192000000 10362578496 10794414080 1389674896 14.503 99 39 79 1 1465853968 65459888 10719184
17:59:56 5882 8192000000 10367045216 10791604224 1397052944 14.569 100 48 96 1 1469291888 59830592 12408352
18:01:07 6001 8192000000 10387463360 10816397312 1417228560 14.748 100 45 90 0 1491279360 61679888 12370912
18:02:17 1636 8192000000 10351324176 10770817024 1385339168 14.464 100 60 120 1 1454747024 53576464 15831392
18:03:29 18969 8192000000 10333367680 10765017088 1348552336 14.134 97 37 71 0 1417842416 59223024 10067056
18:04:58 40180 8192000000 10319235360 10752561152 1339734544 14.055 99 27 54 0 1416686480 68915664 8036272
18:06:39 28316 8192000000 10290725600 10724888576 1314626208 13.828 98 20 40 0 1387186896 66271056 6289632
18:08:09 57974 8192000000 10233420944 10648330240 1270058032 13.422 94 20 37 0 1282238128 9313328 2866768
18:10:10 36288 8192000000 10262748224 10701725696 1340835472 14.065 99 12 24 0 1354676400 11194416 2646512
18:11:48 33891 8192000000 10286989104 10720448512 1371803856 14.343 100 56 102 1 1390032656 6570160 11658640
18:13:38 155438 8192000000 10260152880 10703183872 1322626368 13.900 96 18 37 0 1339140784 12386688 4127728
18:17:14 50911 8192000000 10277442320 10725187584 1362243472 14.257 99 20 42 0 1376486944 9654912 4588560
18:19:05 76194 8192000000 10280558624 10731655168 1379432400 14.411 100 11 22 0 1400015184 17501088 3081696
18:21:24 80995 8192000000 10280969376 10725412864 1362749040 14.262 99 31 62 0 1380209888 9352048 8108800
18:24:10 69699 8192000000 10279520816 10738671616 1368266432 14.312 99 12 25 0 1384307024 13058160 2982432
18:26:31 48447 8192000000 10276205728 10732621824 1373292288 14.357 99 25 49 0 1389283312 10369472 5621552
18:28:20 93171 8192000000 10297324224 10753597440 1388790080 14.495 100 20 40 0 1403750768 10034528 4926160
18:31:00 76903 8192000000 10311558240 10770722816 1404871968 14.638 100 23 46 0 1431324640 20975664 5477008
18:33:32 41936 8192000000 10285418080 10744422400 1362434000 14.259 98 11 22 0 1378798976 13854448 2510528
18:35:15 48218 8192000000 10308088464 10761601024 1400344000 14.598 100 28 56 0 1416441792 9237856 6859936
18:37:05 24515 8192000000 10286412320 10746073088 1388879344 14.496 99 24 48 1 1404419568 9592000 5948224
18:38:29 14538 8192000000 10317407120 10764857344 1406164112 14.650 100 49 94 0 1427076720 10576672 10335936
18:39:45 35869 8192000000 10311016560 10770243584 1382735152 14.441 98 37 74 0 1404924320 14426496 7762672
18:41:22 121880 8192000000 10300066416 10754170880 1311438576 13.799 93 13 24 0 1325771360 11854608 2478176
18:44:24 21321 8192000000 10296400336 10741501952 1384695872 14.459 100 32 65 0 1405317168 13811520 6809776
18:45:46 82147 8192000000 10308747888 10761834496 1335896528 14.020 95 14 28 0 1353556864 14712864 2947472
18:48:08 47762 8192000000 10254675392 10697596928 1251960272 13.256 90 25 50 1 1266759584 9460816 5338496
18:49:57 95836 8192000000 10228576800 10672828416 1275798480 13.475 94 27 53 0 1289485632 7626544 6060608
18:52:35 34239 8192000000 10248278144 10685804544 1320062848 13.877 98 44 88 1 1338158352 8155840 9939664
18:54:09 59403 8192000000 10254583040 10712408064 1340871952 14.065 99 10 20 0 1359210112 15939616 2398544
18:56:10 85529 8192000000 10251147056 10703613952 1310267632 13.788 96 20 39 0 1333505232 18607120 4630480
18:58:35 70776 8192000000 10264194816 10718953472 1344924016 14.102 99 10 20 0 1360348384 13384224 2040144
19:00:57 74818 8192000000 10262831216 10717102080 1326909008 13.939 97 20 40 0 1343494080 12159472 4425600
19:03:25 72239 8192000000 10257746928 10697760768 1327279792 13.943 98 20 40 0 1340971264 9429152 4262320
19:05:42 39271 8192000000 10288692704 10728247296 1344543632 14.098 98 37 74 0 1368335728 15504240 8287856
19:07:23 69735 8192000000 10273891120 10729439232 1330193872 13.969 98 16 32 0 1350201376 16082656 3924848
19:09:48 51005 8192000000 10277920768 10733785088 1367140960 14.301 100 20 40 0 1388419872 16974512 4304400
19:12:24 147800 8192000000 10298614320 10746376192 1371075808 14.337 99 21 42 0 1387125424 11249520 4800096
19:16:27 30538 8192000000 10281732272 10749636608 1384232416 14.454 100 13 23 0 1398962784 12407648 2322720
19:18:01 40101 8192000000 10290396128 10742444032 1373776240 14.361 99 4 8 0 1382836112 8133088 926784
19:19:50 46910 8192000000 10297090928 10761232384 1387052768 14.480 99 12 24 0 1407169344 16477872 3638704
19:22:04 69859 8192000000 10293072000 10749558784 1352945056 14.174 97 22 44 0 1373354592 15591344 4818192
19:24:27 65580 8192000000 10288504912 10746355712 1376531792 14.386 98 27 54 0 1393524224 10901632 6090800
19:26:44 70939 8192000000 10288576320 10743947264 1364673472 14.279 98 20 40 0 1379778784 10617520 4487792
19:28:57 26249 8192000000 10297329536 10736394240 1357991760 14.219 98 38 76 0 1374989712 8226816 8771136
19:30:26 66566 8192000000 10283536944 10736648192 1354581136 14.189 98 21 42 0 1370663808 11777600 4305072
19:32:46 30097 8192000000 10286404080 10735222784 1354122272 14.185 98 33 66 1 1374297120 12745920 7428928
19:34:22 72230 8192000000 10276813392 10740850688 1309727952 13.784 95 4 8 0 1329557888 19230736 599200
19:36:37 74990 8192000000 10256051088 10710130688 1324883376 13.921 97 28 57 0 1341668864 10191424 6594064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-01-2023 19:40:45 to 30-01-2023 19:41:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 75 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2023 19:41:01 to 30-01-2023 19:41:03.

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          240812
MON$OLDEST_ACTIVE               241610
MON$OLDEST_SNAPSHOT             241610
MON$NEXT_TRANSACTION            241610
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                       2019264
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:1009: 
:1010: mscs-test-161.budzdorov.ru    Mon Jan 30 19:38:42 2023
:1011:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1012: 
:1013: 
:1014: mscs-test-161.budzdorov.ru    Mon Jan 30 19:38:51 2023
:1015:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1016: 
:1017: 
:1018: mscs-test-161.budzdorov.ru    Mon Jan 30 19:38:51 2023
:1019:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1020: 
--- end of diff output ---

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

30.01.23 19:41:04. 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
2023-01-30 19:41:06 No severe PSQL-related problems occured
30.01.23 19:41:08. End of report.