Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.02.2023 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15228812 kB
MemAvailable 30407232 kB
Buffers 0 kB
Cached 16455528 kB
SwapCached 3140 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-02-21 01:38:40
DTS_END                         2023-02-21 01:38:40.1910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53582
TRN_ID                          245557
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: 9133.90 19297 1096068
customer order: creation 178.08 344 21370
customer order: refuse 15.63 333 1876
order to supplier: creation 48.37 1198 5804
order to supplier: removal 3.62 89224 434
invoice (draft): creation 47.08 3413 5649
invoice (draft): removal 50.88 776 6105
invoice accept: apply 42.35 19433 5082
invoice accept: cancel 46.19 774 5543
customer reserve: creation 3870.45 195 464454
customer reserve: removal 4535.41 56 544249
realization accept: apply 87.47 167 10496
realization accept: cancel 75.33 111 9040
payment from customer: creation 60.87 114 7304
payment from customer: removal 6.21 85 745
payment to supplier: creation 49.44 138 5933
payment to supplier: removal 3.83 66 459
service: total inventory turnovers 4.98 1220 598
service: total monetary turnovers 6.98 59 838
service: aggregate perf. data 0.71 27497 85
service: refresh index statistics 0.03 240732 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-02-2023 01:39:35 to 21-02-2023 01:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8142
TEST_TIME 2 5059
TEST_TIME 3 4173
TEST_TIME 4 5529
TEST_TIME 5 5295
TEST_TIME 6 4956
TEST_TIME 7 5104
TEST_TIME 8 5506
TEST_TIME 9 5546
TEST_TIME 10 5684
TEST_TIME 11 6077
TEST_TIME 12 6458
TEST_TIME 13 6637
TEST_TIME 14 6680
TEST_TIME 15 7290
TEST_TIME 16 7845
TEST_TIME 17 7948
TEST_TIME 18 8125
TEST_TIME 19 8060
TEST_TIME 20 8139
TEST_TIME 21 8614
TEST_TIME 22 8745
TEST_TIME 23 8901
TEST_TIME 24 8782
TEST_TIME 25 8709
TEST_TIME 26 9138
TEST_TIME 27 9379
TEST_TIME 28 10005
TEST_TIME 29 9939
TEST_TIME 30 10154
TEST_TIME 31 10749
TEST_TIME 32 10642
TEST_TIME 33 10534
TEST_TIME 34 10274
TEST_TIME 35 10213
TEST_TIME 36 10059
TEST_TIME 37 10105
TEST_TIME 38 9999
TEST_TIME 39 10082
TEST_TIME 40 10069
TEST_TIME 41 9884
TEST_TIME 42 9709
TEST_TIME 43 9870
TEST_TIME 44 9828
TEST_TIME 45 9731
TEST_TIME 46 9736
TEST_TIME 47 9672
TEST_TIME 48 9622
TEST_TIME 49 9643
TEST_TIME 50 9545
TEST_TIME 51 9427
TEST_TIME 52 9320
TEST_TIME 53 9342
TEST_TIME 54 9395
TEST_TIME 55 9339
TEST_TIME 56 9224
TEST_TIME 57 9178
TEST_TIME 58 9120
TEST_TIME 59 9334
TEST_TIME 60 9361
TEST_TIME 61 9335
TEST_TIME 62 9337
TEST_TIME 63 9323
TEST_TIME 64 9340
TEST_TIME 65 9367
TEST_TIME 66 9418
TEST_TIME 67 9376
TEST_TIME 68 9421
TEST_TIME 69 9389
TEST_TIME 70 9371
TEST_TIME 71 9415
TEST_TIME 72 9386
TEST_TIME 73 9440
TEST_TIME 74 9414
TEST_TIME 75 9342
TEST_TIME 76 9295
TEST_TIME 77 9319
TEST_TIME 78 9314
TEST_TIME 79 9277
TEST_TIME 80 9235
TEST_TIME 81 9238
TEST_TIME 82 9217
TEST_TIME 83 9266
TEST_TIME 84 9203
TEST_TIME 85 9157
TEST_TIME 86 9127
TEST_TIME 87 9207
TEST_TIME 88 9155
TEST_TIME 89 9218
TEST_TIME 90 9206
TEST_TIME 91 9201
TEST_TIME 92 9208
TEST_TIME 93 9196
TEST_TIME 94 9211
TEST_TIME 95 9195
TEST_TIME 96 9246
TEST_TIME 97 9279
TEST_TIME 98 9301
TEST_TIME 99 9318
TEST_TIME 100 9309
TEST_TIME 101 9283
TEST_TIME 102 9347
TEST_TIME 103 9263
TEST_TIME 104 9209
TEST_TIME 105 9198
TEST_TIME 106 9192
TEST_TIME 107 9189
TEST_TIME 108 9135
TEST_TIME 109 9104
TEST_TIME 110 9104
TEST_TIME 111 9043
TEST_TIME 112 9075
TEST_TIME 113 9046
TEST_TIME 114 9091
TEST_TIME 115 9079
TEST_TIME 116 9058
TEST_TIME 117 9126
TEST_TIME 118 9185
TEST_TIME 119 9162
TEST_TIME 120 9140

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-02-2023 01:39:39 to 21-02-2023 01:39:43.

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 1526695 1526695 0 0.00 0 31324 7 0 0
doc_list_biud 1526695 1526695 0 0.00 0 46486 26 0 0
sp_add_invoice_to_stock 5147 5082 65 1.26 41 195697 19434 0 65
sp_cancel_adding_invoice 5543 5543 0 0.00 5 63202 774 0 0
sp_cancel_client_order 1876 1876 0 0.00 3 12133 334 0 0
sp_cancel_customer_reserve 544249 544249 0 0.00 0 22696 57 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 3 6474 86 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 4099 67 0 0
sp_cancel_supplier_invoice 6108 6105 3 0.04 2 21140 777 0 3
sp_cancel_supplier_order 434 434 0 0.00 6 810250 89224 0 0
sp_cancel_write_off 9040 9040 0 0.00 1 26735 111 0 0
sp_client_order 21370 21370 0 0.00 3 18241 344 0 0
sp_customer_reserve 464454 464454 0 0.00 0 93209 196 0 0
sp_fill_shopping_cart 32818 32818 0 0.00 1 13675 44 0 0
sp_fill_shopping_cart_clo_res 464447 464447 0 0.00 0 17591 15 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 25737 329 0 0
sp_kill_cost_storno 15787 15787 0 0.00 0 7664 51 0 0
sp_kill_qstorno_ret_qs2qd 1104077 1104077 0 0.00 0 26608 31 0 0
sp_kill_qty_storno 582815 582815 0 0.00 0 46486 67 0 0
sp_lock_selected_doc 21750 21750 0 0.00 0 6833 4 0 0
sp_make_cost_storno 28805 28805 0 0.00 0 19212 57 0 0
sp_multiply_rows_for_pdistr 28805 28805 0 0.00 0 12121 25 0 0
sp_multiply_rows_for_qdistr 21370 21370 0 0.00 0 13894 150 0 0
sp_pay_from_customer 7304 7304 0 0.00 4 33975 114 0 0
sp_pay_to_supplier 5933 5933 0 0.00 4 16465 138 0 0
sp_payment_common 13237 13237 0 0.00 2 33964 32 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 12122 312 0 0
sp_qd_handle_on_invoice_upd_sts 10615 10615 0 0.00 0 46485 319 0 0
sp_qd_handle_on_reserve_upd_sts 10496 10496 0 0.00 0 10998 57 0 0
sp_reserve_write_off 10496 10496 0 0.00 6 17428 167 0 0
sp_supplier_invoice 5649 5649 0 0.00 5 94261 3413 0 0
sp_supplier_order 5804 5804 0 0.00 4 25077 1198 0 0
srv_aggregate_perf_data 85 85 0 0.00 2651 366053 27498 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1273 123948 44540 0 0
srv_find_qd_qs_mism 1047538 1047538 0 0.00 0 31324 6 0 0
srv_make_invnt_saldo 598 598 0 0.00 42 64548 1221 0 0
srv_make_money_saldo 838 838 0 0.00 1 25226 59 0 0
srv_recalc_idx_stat 4 4 0 0.00 66590 611262 240732 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 0 8350 154 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 6851 117 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 11954 19 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 1470 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 325 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 735 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2781 22 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 21998 1485 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 3556 13 0 0
t$perf-norm:sp_client_order 21370 21370 0 0.00 0 14295 14 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 2534 7 0 0
t$perf-norm:sp_pay_from_customer 7304 7304 0 0.00 0 6620 10 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 5680 11 0 0
t$perf-norm:sp_reserve_write_off 10496 10496 0 0.00 0 5223 9 0 0
t$perf-norm:sp_supplier_invoice 5649 5649 0 0.00 0 5557 15 0 0
t$perf-norm:sp_supplier_order 5804 5804 0 0.00 0 2151 13 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 339 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 3079 22 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 1827 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 19 7 0 0
v_add_invoice_to_stock 10274 10144 130 1.26 0 3647 16 0 130
v_all_customers 38294 38294 0 0.00 0 21 1 0 0
v_all_suppliers 31478 31478 0 0.00 0 85 1 0 0
v_all_wares 42740 42740 0 0.00 0 111 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 3003 11 0 0
v_cancel_client_order 3752 3752 0 0.00 0 1833 4 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 3628 10 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 2165 6 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 3564 7 0 0
v_cancel_supplier_invoice 848 842 6 0.70 0 3865 20 0 6
v_cancel_supplier_order 868 868 0 0.00 0 197 2 0 0
v_cancel_write_off 2154 2154 0 0.00 0 2290 14 0 0
v_max_id_clo_ord 5803 5803 0 0.00 0 1759 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 9716 22 0 0
v_max_id_ord_sup 5645 5645 0 0.00 0 6635 6 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 1280 4 0 0
v_max_non_paid_realizn 4169 4169 0 0.00 0 3608 4 0 0
v_min_id_clo_ord 5803 5803 0 0.00 0 3383 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 4041 10 0 0
v_min_id_ord_sup 5645 5645 0 0.00 0 4932 6 0 0
v_min_non_paid_invoice 5933 5933 0 0.00 0 16453 23 0 0
v_min_non_paid_realizn 7304 7304 0 0.00 1 33954 25 0 0
v_our_firm 10716 10716 0 0.00 0 25 1 0 0
v_reserve_write_off 20992 20992 0 0.00 0 3903 6 0 0
x_lock_depdocs_on_canc_invoice 11227 11227 0 0.00 0 16110 115 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 16313 344 0 0
x_make_qstorno_1000_1200 5804 5804 0 0.00 1 25065 1123 0 0
x_make_qstorno_1200_2000 5649 5649 0 0.00 2 94038 3240 0 0
x_make_qstorno_2100_3300 464454 464454 0 0.00 0 93203 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-02-2023 01:39:44 to 21-02-2023 01:39:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:44 2 8192000000 8798023760 8809054208 4681536 0.057 2 1 2 0 4781136 56816 42784
23:09:45 25100 8192000000 9920099600 10230456320 985693696 10.740 100 79 158 1 1012428208 6208384 20526128
23:11:16 13394 8192000000 9955883920 10277470208 1037624240 11.242 100 90 180 0 1069079856 8565408 22890208
23:12:37 15778 8192000000 9979681280 10309816320 1065786624 11.512 100 91 182 0 1098097056 9245504 23064928
23:13:56 10462 8192000000 10026875440 10371129344 1113260208 11.963 100 71 142 0 1140125376 10035600 16829568
23:15:08 10114 8192000000 10079200784 10435690496 1169522176 12.492 100 76 152 0 1198354080 11711344 17120560
23:16:21 5040 8192000000 10116330944 10482229248 1205869184 12.831 100 83 166 0 1237322704 13177968 18275552
23:17:27 3030 8192000000 10151550512 10524913664 1241821792 13.163 100 84 165 2 1272615616 13447968 17345856
23:18:32 1302 8192000000 10184554848 10562637824 1267148752 13.396 100 91 180 2 1301461632 14446784 19866096
23:19:35 1187 8192000000 10213254400 10598469632 1297022384 13.668 100 85 170 0 1329199136 12891680 19285072
23:20:37 20226 8192000000 10194068048 10598715392 1303777072 13.730 100 40 80 2 1320238128 7720400 8740656
23:21:58 3169 8192000000 10202562752 10607927296 1309612704 13.783 100 39 79 1 1327095216 8883744 8598768
23:23:02 8638 8192000000 10199971360 10613325824 1314755120 13.829 100 21 43 0 1325367152 5847328 4764704
23:24:17 31408 8192000000 10197357504 10622447616 1314934688 13.831 100 14 28 1 1323758240 5445408 3378144
23:26:06 6059 8192000000 10214226880 10629140480 1317123120 13.851 100 31 62 0 1331496576 7629664 6743792
23:27:14 4917 8192000000 10226644992 10639794176 1321676512 13.892 100 34 66 0 1335651536 6497152 7477872
23:28:19 8400 8192000000 10212518384 10629783552 1319091888 13.868 100 17 34 0 1328302032 5364640 3845504
23:29:28 63065 8192000000 10120229440 10511507456 1175896992 12.552 91 48 96 1 1192230288 4383776 11949520
23:31:35 6794 8192000000 10126046224 10528714752 1211709680 12.885 96 20 40 0 1221664208 5142864 4811664
23:32:43 4604 8192000000 10138666688 10531807232 1228714592 13.042 99 30 60 0 1241657056 5492720 7449744
23:33:49 13616 8192000000 10161529248 10571288576 1272702752 13.446 100 15 31 1 1281532576 5390064 3439760
23:35:02 42828 8192000000 10184889776 10596401152 1289770320 13.602 100 46 89 0 1306615472 6180752 10664400
23:36:48 5003 8192000000 10202558016 10616606720 1306146416 13.751 100 19 38 1 1316867296 5508752 5212128
23:37:55 7726 8192000000 10219591360 10627203072 1317150080 13.851 100 27 52 0 1327949904 4483120 6316704
23:39:07 3532 8192000000 10259670144 10666594304 1347135376 14.122 100 60 120 0 1368190480 7075296 13979808
23:40:15 106262 8192000000 10274668928 10695028736 1366005360 14.291 100 51 100 0 1388157808 10459696 11692752
23:43:06 47820 8192000000 10281303568 10715242496 1377739120 14.396 100 27 54 0 1396112944 12078816 6295008
23:45:07 7767 8192000000 10298292032 10726236160 1391961488 14.523 100 39 77 2 1413981728 12096912 9923328
23:46:19 9066 8192000000 10309516464 10732605440 1390982688 14.515 100 43 86 1 1418695008 18047600 9664720
23:47:32 15153 8192000000 10320508528 10742087680 1398502192 14.582 100 46 92 1 1430801120 21549232 10749696
23:48:57 7441 8192000000 10325552112 10749800448 1401535792 14.609 100 56 109 1 1437411616 23574416 12301408
23:50:11 4942 8192000000 10342127984 10762981376 1415024864 14.729 100 72 143 0 1456396944 24859776 16512304
23:51:22 4452 8192000000 10345216688 10756456448 1404786640 14.638 100 73 144 0 1446431504 24797632 16847232
23:52:30 1272 8192000000 10338910016 10746368000 1388164768 14.489 100 79 156 0 1440017520 32871440 18981312
23:53:36 6177 8192000000 10318762560 10732785664 1372163056 14.346 100 68 133 2 1421879840 33947632 15769152
23:54:50 2438 8192000000 10314182592 10736349184 1382029328 14.435 100 54 108 0 1421725184 26181248 13514608
23:55:59 4466 8192000000 10324008752 10747371520 1389502192 14.501 100 66 131 0 1433956880 28694032 15760656
23:57:08 3032 8192000000 10313138944 10730332160 1370529568 14.332 100 61 122 0 1414903136 29060720 15312848
23:58:20 3179 8192000000 10338364832 10750988288 1382369856 14.438 100 62 124 2 1438182848 40032432 15780560
23:59:25 12374 8192000000 10309119872 10731618304 1370954272 14.336 100 40 80 0 1416478016 34874144 10649600
00:00:44 5061 8192000000 10309208352 10721406976 1352646576 14.171 99 49 98 1 1402861840 37277760 12937504
00:01:54 11140 8192000000 10325663872 10743009280 1377944304 14.398 100 56 112 0 1427968576 35895680 14128592
00:03:17 4688 8192000000 10322859824 10739265536 1367104992 14.301 100 64 124 0 1428974000 45220464 16648544
00:04:26 9198 8192000000 10299264416 10720198656 1344076080 14.094 99 32 64 0 1401390128 47865200 9448848
00:05:39 20231 8192000000 10301802784 10736033792 1352582576 14.171 99 29 57 0 1406876176 45973072 8320528
00:07:01 32279 8192000000 10258798464 10692829184 1306606560 13.755 97 13 26 0 1335401488 25616192 3178736
00:08:49 88357 8192000000 10284501072 10731798528 1366356016 14.294 100 21 43 1 1402944736 31278928 5309792
00:11:21 82331 8192000000 10302627328 10734981120 1380507712 14.421 100 57 105 2 1398974400 6318624 12148064
00:13:59 114352 8192000000 10298802992 10758778880 1381131408 14.427 99 21 42 0 1399104384 12733648 5239328
00:16:53 70805 8192000000 10289046224 10740547584 1348130576 14.131 97 13 26 0 1368626784 17857552 2638656
00:19:12 64940 8192000000 10310075712 10755977216 1395657936 14.556 100 42 86 2 1416174128 10596448 9919744
00:21:18 60585 8192000000 10311766992 10778390528 1404611600 14.636 100 13 26 0 1423023248 15259488 3152160
00:23:20 79666 8192000000 10302231456 10763395072 1396831648 14.567 99 20 40 0 1408577696 7497040 4249008
00:25:53 30556 8192000000 10325060880 10769674240 1397862928 14.576 99 47 92 2 1418077312 9376608 10837776
00:27:33 42056 8192000000 10301932368 10769195008 1406472128 14.653 100 22 44 1 1424338912 12810592 5056192
00:29:15 34597 8192000000 10319337440 10759843840 1395295440 14.553 99 39 77 3 1413733760 9795712 8642608
00:30:51 34860 8192000000 10329099888 10776367104 1411910528 14.701 100 52 104 0 1441622160 17406384 12305248
00:32:41 72190 8192000000 10318949520 10792050688 1411652992 14.699 100 11 22 0 1440900576 26495872 2751712
00:35:31 92453 8192000000 10303517056 10768195584 1395648224 14.556 99 23 46 0 1422029344 20344224 6036896
00:38:15 67405 8192000000 10307785840 10765496320 1378122032 14.400 98 13 26 0 1390891520 10809040 1960448
00:40:27 49455 8192000000 10309061648 10759245824 1342239072 14.078 95 16 32 0 1354456864 8734352 3483440
00:42:18 20324 8192000000 10294252448 10732969984 1370747920 14.334 99 48 94 1 1390429664 8637072 11044672
00:43:46 107179 8192000000 10258437264 10716962816 1245516544 13.197 90 12 25 0 1261299424 12527328 3255552
00:46:33 84646 8192000000 10264196592 10705321984 1288514256 13.591 94 20 40 0 1304666576 11961616 4190704
00:49:04 39357 8192000000 10262311760 10703626240 1337502384 14.035 99 31 62 0 1356430080 12306496 6621200
00:50:51 27885 8192000000 10275037456 10708869120 1353153136 14.176 100 40 80 0 1372950752 11234928 8562688
00:52:22 32715 8192000000 10279191952 10712322048 1343326144 14.087 98 37 75 0 1359520880 7158144 9036592
00:53:57 79748 8192000000 10275827984 10727534592 1367655312 14.306 100 32 63 0 1382521104 7314864 7550928
00:56:50 41261 8192000000 10261550000 10698846208 1330985520 13.976 98 31 62 1 1346604288 9065296 6553472
00:58:36 83094 8192000000 10268061360 10723622912 1356701152 14.208 99 16 32 0 1371013392 10447280 3864960
01:01:00 35469 8192000000 10247760240 10710056960 1316259456 13.843 96 9 18 0 1330505008 12356368 1889184
01:02:37 63649 8192000000 10255596080 10711105536 1356446704 14.205 100 16 32 0 1368617376 8212688 3957984
01:05:34 81420 8192000000 10274591808 10719350784 1328256832 13.951 97 16 33 0 1341992784 9900288 3835664
01:07:59 30207 8192000000 10256344736 10702241792 1326760128 13.938 97 17 34 0 1337218864 7004176 3454560
01:09:33 39568 8192000000 10296063168 10744696832 1369984416 14.327 99 30 60 0 1388784080 12437984 6361680
01:11:15 18365 8192000000 10306125792 10752606208 1393720768 14.539 100 21 43 0 1405869328 7615600 4532960
01:12:38 37580 8192000000 10313511520 10760867840 1375169632 14.373 98 30 60 0 1397240448 15569472 6501344
01:14:21 33873 8192000000 10302101104 10749280256 1364849440 14.281 98 29 58 0 1381394688 10214352 6330896
01:16:00 86820 8192000000 10308405344 10760155136 1361768096 14.253 97 14 28 0 1375637488 10611840 3257552
01:18:35 52937 8192000000 10325506688 10771632128 1372178880 14.347 98 30 60 0 1391852560 13371440 6302240
01:20:33 123948 8192000000 10281018336 10724769792 1311918384 13.803 94 24 48 0 1324900944 8131712 4850848
01:23:41 47144 8192000000 10287424240 10745708544 1366544944 14.296 99 20 40 0 1387778096 17071824 4161328
01:25:29 45345 8192000000 10287723712 10747535360 1375770416 14.379 99 13 26 0 1386495616 8074816 2650384
01:27:15 74186 8192000000 10296309536 10758721536 1377309136 14.392 99 20 40 0 1393986928 11600384 5077408
01:29:37 83695 8192000000 10264632976 10728882176 1327965056 13.949 96 16 32 0 1343759936 11546256 4248624
01:32:01 52260 8192000000 10259954064 10721435648 1335156080 14.014 98 13 26 0 1351880224 13511104 3213040
01:33:54 35136 8192000000 10272055040 10710568960 1336230320 14.023 98 43 86 1 1359041696 12913808 9897568
01:35:30 71853 8192000000 10235407680 10692694016 1311494352 13.800 97 14 28 0 1328508464 13491968 3522144
01:37:42 58784 8192000000 9631475616 9980268544 736056224 8.244 54 11 22 0 746278416 7672592 2549600

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-02-2023 01:39:50 to 21-02-2023 01:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 65 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-02-2023 01:40:06 to 21-02-2023 01:40:08.

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          246250
MON$OLDEST_ACTIVE               246251
MON$OLDEST_SNAPSHOT             246251
MON$NEXT_TRANSACTION            246251
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                       2038240
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:341: 
:342: mscs-test-161.budzdorov.ru    Tue Feb 21 01:38:50 2023
:343:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:344: 
--- end of diff output ---

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

21.02.23 01:40:09. 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-02-21 01:40:12 No severe PSQL-related problems occured
21.02.23 01:40:13. End of report.