Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.2023 01: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 15364064 kB
MemAvailable 30441012 kB
Buffers 0 kB
Cached 16372888 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-24 01:38:41
DTS_END                         2023-02-24 01:38:41.3060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42724
TRN_ID                          235385
ATT_ID                          609
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: 9059.79 21132 1087175
customer order: creation 166.73 352 20007
customer order: refuse 14.57 334 1748
order to supplier: creation 45.37 1279 5444
order to supplier: removal 3.46 97304 415
invoice (draft): creation 43.81 3180 5257
invoice (draft): removal 52.19 802 6263
invoice accept: apply 40.17 17386 4821
invoice accept: cancel 47.26 818 5671
customer reserve: creation 3771.85 170 452622
customer reserve: removal 4585.93 59 550312
realization accept: apply 81.58 166 9789
realization accept: cancel 81.53 107 9783
payment from customer: creation 57.49 99 6899
payment from customer: removal 5.58 82 669
payment to supplier: creation 47.13 126 5656
payment to supplier: removal 3.55 57 426
service: total inventory turnovers 4.48 1325 538
service: total monetary turnovers 6.30 82 756
service: aggregate perf. data 0.79 21705 95
service: refresh index statistics 0.03 277198 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-02-2023 01:40:27 to 24-02-2023 01:40:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6395
TEST_TIME 2 6277
TEST_TIME 3 6228
TEST_TIME 4 5685
TEST_TIME 5 5178
TEST_TIME 6 4959
TEST_TIME 7 5136
TEST_TIME 8 5291
TEST_TIME 9 5886
TEST_TIME 10 5962
TEST_TIME 11 6379
TEST_TIME 12 6471
TEST_TIME 13 7254
TEST_TIME 14 7719
TEST_TIME 15 8091
TEST_TIME 16 8085
TEST_TIME 17 8238
TEST_TIME 18 8216
TEST_TIME 19 8642
TEST_TIME 20 8784
TEST_TIME 21 8725
TEST_TIME 22 8890
TEST_TIME 23 8956
TEST_TIME 24 9307
TEST_TIME 25 9668
TEST_TIME 26 10258
TEST_TIME 27 11410
TEST_TIME 28 11160
TEST_TIME 29 10907
TEST_TIME 30 10877
TEST_TIME 31 10728
TEST_TIME 32 10842
TEST_TIME 33 10662
TEST_TIME 34 10654
TEST_TIME 35 10479
TEST_TIME 36 10332
TEST_TIME 37 10207
TEST_TIME 38 10034
TEST_TIME 39 10052
TEST_TIME 40 9952
TEST_TIME 41 9839
TEST_TIME 42 9674
TEST_TIME 43 9671
TEST_TIME 44 9607
TEST_TIME 45 9596
TEST_TIME 46 9531
TEST_TIME 47 9539
TEST_TIME 48 9459
TEST_TIME 49 9339
TEST_TIME 50 9332
TEST_TIME 51 9305
TEST_TIME 52 9242
TEST_TIME 53 9203
TEST_TIME 54 9188
TEST_TIME 55 9188
TEST_TIME 56 9162
TEST_TIME 57 9160
TEST_TIME 58 9172
TEST_TIME 59 9203
TEST_TIME 60 9212
TEST_TIME 61 9325
TEST_TIME 62 9271
TEST_TIME 63 9430
TEST_TIME 64 9402
TEST_TIME 65 9428
TEST_TIME 66 9460
TEST_TIME 67 9436
TEST_TIME 68 9459
TEST_TIME 69 9359
TEST_TIME 70 9313
TEST_TIME 71 9218
TEST_TIME 72 9280
TEST_TIME 73 9249
TEST_TIME 74 9182
TEST_TIME 75 9168
TEST_TIME 76 9101
TEST_TIME 77 9101
TEST_TIME 78 9098
TEST_TIME 79 9105
TEST_TIME 80 9116
TEST_TIME 81 9107
TEST_TIME 82 9097
TEST_TIME 83 9152
TEST_TIME 84 9161
TEST_TIME 85 9219
TEST_TIME 86 9212
TEST_TIME 87 9270
TEST_TIME 88 9292
TEST_TIME 89 9388
TEST_TIME 90 9373
TEST_TIME 91 9401
TEST_TIME 92 9337
TEST_TIME 93 9254
TEST_TIME 94 9219
TEST_TIME 95 9248
TEST_TIME 96 9179
TEST_TIME 97 9169
TEST_TIME 98 9150
TEST_TIME 99 9131
TEST_TIME 100 9099
TEST_TIME 101 9115
TEST_TIME 102 9105
TEST_TIME 103 9141
TEST_TIME 104 9170
TEST_TIME 105 9144
TEST_TIME 106 9170
TEST_TIME 107 9206
TEST_TIME 108 9226
TEST_TIME 109 9222
TEST_TIME 110 9253
TEST_TIME 111 9257
TEST_TIME 112 9279
TEST_TIME 113 9288
TEST_TIME 114 9225
TEST_TIME 115 9171
TEST_TIME 116 9182
TEST_TIME 117 9137
TEST_TIME 118 9106
TEST_TIME 119 9089
TEST_TIME 120 9065

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-02-2023 01:40:31 to 24-02-2023 01:40:34.

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 1504957 1504957 0 0.00 0 29820 6 0 0
doc_list_biud 1504957 1504957 0 0.00 0 30046 27 0 0
sp_add_invoice_to_stock 4928 4821 107 2.17 19 173151 17387 0 107
sp_cancel_adding_invoice 5671 5671 0 0.00 3 65468 818 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 8388 334 0 0
sp_cancel_customer_reserve 550312 550312 0 0.00 0 30050 59 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 5438 82 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 3289 57 0 0
sp_cancel_supplier_invoice 6269 6263 6 0.09 1 30128 803 0 6
sp_cancel_supplier_order 415 415 0 0.00 6 1052892 97304 0 0
sp_cancel_write_off 9783 9783 0 0.00 1 29660 107 0 0
sp_client_order 20007 20007 0 0.00 3 29343 353 0 0
sp_customer_reserve 452622 452622 0 0.00 0 93761 170 0 0
sp_fill_shopping_cart 30706 30706 0 0.00 1 32378 42 0 0
sp_fill_shopping_cart_clo_res 452610 452610 0 0.00 0 29558 13 0 0
sp_get_clo_for_invoice 4808 4808 0 0.00 0 21113 332 0 0
sp_kill_cost_storno 16549 16549 0 0.00 0 29609 53 0 0
sp_kill_qstorno_ret_qs2qd 1117085 1117085 0 0.00 0 30037 32 0 0
sp_kill_qty_storno 588789 588789 0 0.00 0 30042 69 0 0
sp_lock_selected_doc 20216 20216 0 0.00 0 9852 7 0 0
sp_make_cost_storno 27152 27152 0 0.00 0 14392 48 0 0
sp_multiply_rows_for_pdistr 27152 27152 0 0.00 0 17436 28 0 0
sp_multiply_rows_for_qdistr 20007 20007 0 0.00 0 10132 156 0 0
sp_pay_from_customer 6899 6899 0 0.00 4 17805 99 0 0
sp_pay_to_supplier 5656 5656 0 0.00 4 22580 127 0 0
sp_payment_common 12555 12555 0 0.00 2 7889 25 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 8358 310 0 0
sp_qd_handle_on_invoice_upd_sts 10479 10479 0 0.00 0 16983 307 0 0
sp_qd_handle_on_reserve_upd_sts 9789 9789 0 0.00 0 15148 62 0 0
sp_reserve_write_off 9789 9789 0 0.00 6 15201 167 0 0
sp_supplier_invoice 5257 5257 0 0.00 6 77793 3181 0 0
sp_supplier_order 5444 5444 0 0.00 4 35633 1279 0 0
srv_aggregate_perf_data 95 95 0 0.00 1946 330579 21706 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1889 126541 46559 0 0
srv_find_qd_qs_mism 1040051 1040051 0 0.00 0 29819 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 34 144304 1326 0 0
srv_make_money_saldo 756 756 0 0.00 1 42801 83 0 0
srv_recalc_idx_stat 4 4 0 0.00 96387 519765 277198 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 0 21945 183 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 15460 141 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 14018 21 0 0
t$perf-norm:sp_cancel_customer_reserve 590 590 0 0.00 0 1166 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 1751 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1028 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 452 7 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 35000 1588 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 1304 7 0 0
t$perf-norm:sp_client_order 20007 20007 0 0.00 0 13592 18 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 5244 16 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 10728 12 0 0
t$perf-norm:sp_pay_to_supplier 5656 5656 0 0.00 0 9059 15 0 0
t$perf-norm:sp_reserve_write_off 9789 9789 0 0.00 0 7283 12 0 0
t$perf-norm:sp_supplier_invoice 5257 5257 0 0.00 0 5832 21 0 0
t$perf-norm:sp_supplier_order 5444 5444 0 0.00 0 7580 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 106 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 297 14 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 658 18 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 2193 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 50 14 0 0
v_add_invoice_to_stock 9820 9606 214 2.17 0 11350 18 0 214
v_all_customers 36362 36362 0 0.00 0 69 1 0 0
v_all_suppliers 29758 29758 0 0.00 0 39 1 0 0
v_all_wares 40012 40012 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 3325 11 0 0
v_cancel_client_order 3496 3496 0 0.00 0 4973 4 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 4878 13 0 0
v_cancel_customer_reserve 1180 1180 0 0.00 0 1761 5 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 3269 9 0 0
v_cancel_supplier_invoice 748 736 12 1.60 0 1302 14 0 12
v_cancel_supplier_order 830 830 0 0.00 0 1409 5 0 0
v_cancel_write_off 1952 1952 0 0.00 0 5496 22 0 0
v_max_id_clo_ord 5442 5442 0 0.00 0 579 1 0 0
v_max_id_clo_res 943 943 0 0.00 0 2795 16 0 0
v_max_id_ord_sup 5257 5257 0 0.00 0 6621 7 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 1259 3 0 0
v_max_non_paid_realizn 3769 3769 0 0.00 0 6846 6 0 0
v_min_id_clo_ord 5442 5442 0 0.00 0 6119 7 0 0
v_min_id_clo_res 943 943 0 0.00 0 4012 14 0 0
v_min_id_ord_sup 5257 5257 0 0.00 0 5817 7 0 0
v_min_non_paid_invoice 5656 5656 0 0.00 0 7884 19 0 0
v_min_non_paid_realizn 6899 6899 0 0.00 0 7072 18 0 0
v_our_firm 9910 9910 0 0.00 0 22 1 0 0
v_reserve_write_off 19578 19578 0 0.00 0 5097 7 0 0
x_lock_depdocs_on_canc_invoice 11566 11566 0 0.00 0 43211 112 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 2518 286 0 0
x_make_qstorno_1000_1200 5444 5444 0 0.00 1 35621 1192 0 0
x_make_qstorno_1200_2000 5257 5257 0 0.00 3 77692 3031 0 0
x_make_qstorno_2100_3300 452622 452622 0 0.00 0 93736 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2023 01:40:35 to 24-02-2023 01:40:39.

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:45 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:45 13584 8192000000 9890838080 10201960448 976381936 10.649 100 85 170 0 1004284816 5901728 22001152
23:11:04 6401 8192000000 9934138864 10253336576 1018515152 11.058 100 85 170 0 1048959040 8418096 22025792
23:12:14 8500 8192000000 9961338496 10286735360 1046537184 11.327 100 89 178 0 1078653360 9114608 23001568
23:13:31 11306 8192000000 10000475488 10341421056 1088546128 11.729 100 73 146 0 1116838160 10755104 17536928
23:14:47 6550 8192000000 10047856064 10396565504 1132808048 12.148 100 83 166 0 1165844672 13208864 19827760
23:15:56 3424 8192000000 10088513136 10451386368 1178178880 12.573 100 77 154 0 1208974368 13714752 17080736
23:17:01 3132 8192000000 10143013456 10511929344 1229192976 13.047 100 77 151 1 1258307280 11977472 17136832
23:18:06 1233 8192000000 10175180176 10555039744 1259373568 13.324 100 87 175 0 1290818368 11696000 19748800
23:19:09 1258 8192000000 10214697456 10597961728 1300381392 13.699 100 94 188 0 1336214272 15075616 20757264
23:20:11 4186 8192000000 10218188416 10613178368 1309654688 13.783 100 64 127 1 1333591040 9803600 14132752
23:21:16 8468 8192000000 10200459952 10614804480 1316392896 13.844 100 19 38 1 1327718416 7506032 3819488
23:22:25 15231 8192000000 10208144352 10628427776 1326832160 13.939 100 15 30 0 1336077568 5778720 3466688
23:23:42 18101 8192000000 10223220736 10644312064 1331772816 13.983 100 40 80 1 1346871024 4882512 10215696
23:25:00 6143 8192000000 10227802368 10650124288 1339197472 14.050 100 22 42 0 1349716576 5603392 4915712
23:26:07 6558 8192000000 10237851472 10662637568 1349831392 14.146 100 22 44 0 1364869280 9837008 5200880
23:27:18 10406 8192000000 10233692304 10651889664 1328628640 13.955 98 22 45 0 1338936240 5486928 4820672
23:28:29 18691 8192000000 10228767968 10647683072 1296123008 13.660 96 17 35 0 1305509728 5385664 4001056
23:29:50 9760 8192000000 10172208160 10566762496 1227268656 13.029 94 19 38 0 1236624272 4846320 4509296
23:31:01 6160 8192000000 10144434800 10545217536 1217873520 12.942 96 29 57 0 1230235312 5871056 6490736
23:32:12 63752 8192000000 10126468160 10525847552 1196531344 12.744 95 28 56 0 1212034464 9198176 6304944
23:34:17 6145 8192000000 10174907136 10580451328 1281446560 13.526 100 29 58 0 1293932240 5595920 6889760
23:35:25 7147 8192000000 10183697696 10595823616 1293816992 13.639 100 19 38 0 1303860240 5297216 4746032
23:36:33 14456 8192000000 10200710768 10616266752 1309615792 13.783 100 19 38 1 1319837888 5485808 4736288
23:37:50 31203 8192000000 10205926912 10625163264 1318461664 13.863 100 22 44 2 1329072528 5445888 5164976
23:39:25 3743 8192000000 10243453152 10652676096 1338877264 14.047 100 47 91 1 1359057776 10089264 10091248
23:40:36 13484 8192000000 10270713600 10688798720 1362885216 14.263 100 47 94 1 1385027904 11054288 11088400
23:41:51 11938 8192000000 10291240960 10709835776 1381008752 14.426 100 52 104 0 1406831248 13966208 11856288
23:43:15 7366 8192000000 10298520496 10721452032 1390537504 14.511 100 37 74 1 1412973456 13242960 9192992
23:44:28 11066 8192000000 10314106784 10735828992 1399165328 14.588 100 40 79 1 1424855472 15791472 9898672
23:45:44 20692 8192000000 10319224656 10743472128 1400694000 14.601 100 43 87 0 1432030608 20750832 10585776
23:47:20 1885 8192000000 10350702384 10770874368 1417848592 14.754 100 69 135 2 1461523840 26248528 17426720
23:48:25 12006 8192000000 10353119680 10776358912 1418210656 14.757 100 55 107 2 1459168800 26864944 14093200
23:49:41 9495 8192000000 10352455904 10782208000 1420110016 14.774 100 60 118 0 1465805136 30505248 15189872
23:50:57 4791 8192000000 10363061840 10789228544 1420286144 14.775 100 61 123 1 1471753168 35870400 15596624
23:52:10 6359 8192000000 10355728256 10774945792 1409673296 14.681 100 73 144 3 1465020448 38015824 17331328
23:53:20 3548 8192000000 10357902336 10778546176 1406274064 14.651 100 64 127 0 1459203168 37587408 15341696
23:54:29 2763 8192000000 10339609520 10756280320 1394067248 14.542 100 63 126 0 1448075888 38718480 15290160
23:55:40 10680 8192000000 10345260848 10773094400 1403730368 14.628 100 35 68 0 1449761776 37212688 8818720
23:56:54 14311 8192000000 10337624912 10756870144 1347713696 14.127 96 38 75 1 1403517488 45263040 10540752
23:58:17 5831 8192000000 10325570912 10742263808 1371158976 14.337 98 52 102 2 1421698256 37417088 13122192
23:59:28 2111 8192000000 10346109760 10759557120 1378756560 14.405 99 68 131 0 1438448544 43278480 16413504
00:00:33 11798 8192000000 10337657408 10762866688 1380929744 14.425 100 37 73 0 1440219568 49086384 10203440
00:01:49 14801 8192000000 10353234832 10779545600 1374461008 14.367 98 35 70 0 1438824096 54215600 10147488
00:03:12 17192 8192000000 10337398608 10771197952 1385038160 14.462 100 34 68 0 1444776208 49690912 10047136
00:04:46 36337 8192000000 10289238880 10728480768 1325079216 13.923 98 22 44 1 1374124768 42466576 6578976
00:06:23 86857 8192000000 10248073536 10684178432 1306729824 13.756 97 30 59 0 1330541872 16723520 7088528
00:08:52 25851 8192000000 10245165216 10684305408 1307757856 13.766 97 19 38 0 1330640800 18066752 4816192
00:10:18 84195 8192000000 10255705584 10692976640 1323272864 13.906 98 26 50 1 1346725360 17546928 5905568
00:12:45 97911 8192000000 10269235440 10716569600 1353622576 14.180 99 11 22 0 1366276480 10364480 2289424
00:15:30 76571 8192000000 10286789056 10731020288 1374506720 14.367 100 28 56 1 1394670512 13456000 6707792
00:17:47 92276 8192000000 10277566736 10735915008 1388140144 14.489 100 22 44 0 1402404752 9516000 4748608
00:20:20 11733 8192000000 10310569664 10744000512 1387962656 14.488 100 61 121 1 1411861728 10045344 13853728
00:21:36 32820 8192000000 10287856224 10741608448 1392788496 14.531 100 31 62 0 1411816368 12093040 6934832
00:23:09 70396 8192000000 10297226448 10756915200 1391137776 14.516 99 18 34 1 1406850912 11746992 3966144
00:25:31 64328 8192000000 10301365072 10766675968 1408052848 14.667 100 18 36 0 1424600144 11575216 4972080
00:27:48 44377 8192000000 10296889920 10762764288 1393877984 14.540 99 22 42 0 1409546112 10207376 5460752
00:29:42 55560 8192000000 10300005696 10769666048 1395497328 14.555 99 16 32 0 1407464864 8670736 3296800
00:31:39 92747 8192000000 10297613424 10770288640 1392462688 14.528 99 7 14 0 1404726224 10779552 1483984
00:34:12 43468 8192000000 10299780336 10766893056 1396569664 14.564 99 9 16 0 1405350160 6896880 1883616
00:35:58 57562 8192000000 10289248160 10754953216 1358235440 14.222 97 24 48 0 1373856048 10494768 5125840
00:37:56 64001 8192000000 10289521360 10755592192 1345963648 14.111 96 9 18 0 1362465008 14828640 1672720
00:41:24 80224 8192000000 10297226752 10745528320 1325715776 13.928 95 10 20 0 1335478320 8067200 1695344
00:43:47 37373 8192000000 10285561680 10736758784 1364461136 14.277 99 21 41 1 1379676848 11140272 4075440
00:45:26 29296 8192000000 10299332208 10740060160 1360884560 14.245 99 41 82 0 1380500800 10719840 8896400
00:47:01 96121 8192000000 10252045680 10701287424 1321303888 13.889 97 26 52 0 1341344912 13919664 6121360
00:49:47 15587 8192000000 10279558512 10705846272 1344576272 14.099 99 70 140 0 1373142800 12891824 15674704
00:51:07 54590 8192000000 10251904208 10713853952 1350532160 14.152 99 8 16 1 1359446352 7099120 1815072
00:53:01 48975 8192000000 10242305008 10697838592 1316189424 13.842 96 19 38 0 1328358784 7855088 4314272
00:54:55 77355 8192000000 10239257968 10700095488 1316063936 13.841 97 16 32 0 1334292224 14457344 3770944
00:57:15 126253 8192000000 10250576016 10705973248 1277012896 13.486 94 12 24 0 1292937024 13273648 2650480
01:00:54 47057 8192000000 10236432096 10688933888 1306194912 13.752 97 21 42 0 1322417456 11363088 4859456
01:02:55 49136 8192000000 10246004592 10695180288 1311684960 13.801 97 16 32 2 1331893856 16452144 3756752
01:04:51 35363 8192000000 10264153920 10704216064 1343740448 14.091 99 29 58 0 1360421584 10553600 6127536
01:06:33 51410 8192000000 10280217936 10734546944 1365489648 14.287 100 16 32 0 1384182336 15336384 3356304
01:08:32 64490 8192000000 10300476032 10739335168 1353237584 14.177 98 23 46 0 1376586912 18125568 5223760
01:10:39 98793 8192000000 10295885504 10738946048 1374044192 14.363 99 36 69 0 1388828944 7633920 7150832
01:13:19 47802 8192000000 10282828096 10749243392 1375631792 14.377 99 20 38 0 1386274928 6669424 3973712
01:15:29 54583 8192000000 10292265376 10759897088 1399348288 14.589 100 6 12 0 1412468976 11676688 1444000
01:17:24 43591 8192000000 10286618992 10751946752 1393339152 14.536 100 14 28 0 1406689136 10430960 2919024
01:19:14 67769 8192000000 10296378528 10758414336 1384912272 14.460 99 13 26 2 1395850560 8265632 2672656
01:21:24 39809 8192000000 10290278544 10753351680 1372964048 14.354 98 14 28 0 1384642096 8418432 3259616
01:23:05 73853 8192000000 10293431040 10751815680 1348391712 14.133 96 16 32 0 1361734240 9879664 3462864
01:25:19 126540 8192000000 10307667856 10767282176 1314572400 13.828 94 10 20 0 1329088432 12372512 2143520
01:28:27 95275 8192000000 10309634640 10757210112 1325883136 13.930 95 14 28 0 1339372176 10557824 2931216
01:31:03 124168 8192000000 10256061648 10710536192 1320215808 13.879 95 17 34 0 1332113776 8357472 3540496
01:34:13 66084 8192000000 10259531584 10722750464 1340124784 14.059 97 13 26 0 1350899184 7997584 2776816
01:36:40 58822 8192000000 10270548752 10726952960 1339639936 14.054 98 4 8 0 1353249536 12676368 933232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-02-2023 01:40:41 to 24-02-2023 01:40:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 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 24-02-2023 01:40:58 to 24-02-2023 01:41:00.

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          234279
MON$OLDEST_ACTIVE               235506
MON$OLDEST_SNAPSHOT             235506
MON$NEXT_TRANSACTION            235506
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                       2024192
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 ---
:513: 
:514: mscs-test-161.budzdorov.ru    Fri Feb 24 01:38:44 2023
:515:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:516: 
:517: 
:518: mscs-test-161.budzdorov.ru    Fri Feb 24 01:38:50 2023
:519:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:520: 
:521: 
:522: mscs-test-161.budzdorov.ru    Fri Feb 24 01:38:50 2023
:523:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:524: 
:525: 
:526: mscs-test-161.budzdorov.ru    Fri Feb 24 01:38:50 2023
:527:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:528: 
--- end of diff output ---

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

24.02.23 01:41:01. 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-24 01:41:03 No severe PSQL-related problems occured
24.02.23 01:41:05. End of report.