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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-23 22:39:02
DTS_END                         2022-10-23 22:38:33.6780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37758
TRN_ID                          243503
ATT_ID                          274
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: 9145.53 22866 1097463
customer order: creation 178.33 345 21399
customer order: refuse 15.78 393 1893
order to supplier: creation 48.86 1317 5863
order to supplier: removal 3.22 95329 386
invoice (draft): creation 47.88 3483 5746
invoice (draft): removal 50.24 798 6029
invoice accept: apply 42.86 18180 5143
invoice accept: cancel 45.37 754 5444
customer reserve: creation 3930.66 182 471679
customer reserve: removal 4484.90 53 538188
realization accept: apply 86.38 171 10365
realization accept: cancel 78.38 102 9405
payment from customer: creation 60.90 109 7308
payment from customer: removal 6.03 83 723
payment to supplier: creation 50.38 116 6045
payment to supplier: removal 3.61 79 433
service: total inventory turnovers 4.55 1307 546
service: total monetary turnovers 6.29 30 755
service: aggregate perf. data 0.91 20768 109
service: refresh index statistics 0.03 313713 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2022 22:40:28 to 23-10-2022 22:40:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6167
TEST_TIME 2 5810
TEST_TIME 3 6063
TEST_TIME 4 5492
TEST_TIME 5 5609
TEST_TIME 6 5690
TEST_TIME 7 5564
TEST_TIME 8 5661
TEST_TIME 9 5778
TEST_TIME 10 5952
TEST_TIME 11 6024
TEST_TIME 12 6243
TEST_TIME 13 6380
TEST_TIME 14 7081
TEST_TIME 15 7285
TEST_TIME 16 7399
TEST_TIME 17 7438
TEST_TIME 18 7577
TEST_TIME 19 7481
TEST_TIME 20 8406
TEST_TIME 21 8388
TEST_TIME 22 8334
TEST_TIME 23 8673
TEST_TIME 24 9074
TEST_TIME 25 9219
TEST_TIME 26 9436
TEST_TIME 27 9984
TEST_TIME 28 10600
TEST_TIME 29 10874
TEST_TIME 30 10802
TEST_TIME 31 10598
TEST_TIME 32 10408
TEST_TIME 33 10492
TEST_TIME 34 10279
TEST_TIME 35 10243
TEST_TIME 36 10186
TEST_TIME 37 10327
TEST_TIME 38 10167
TEST_TIME 39 10026
TEST_TIME 40 9872
TEST_TIME 41 9952
TEST_TIME 42 9926
TEST_TIME 43 9754
TEST_TIME 44 9689
TEST_TIME 45 9563
TEST_TIME 46 9522
TEST_TIME 47 9546
TEST_TIME 48 9452
TEST_TIME 49 9401
TEST_TIME 50 9329
TEST_TIME 51 9221
TEST_TIME 52 9187
TEST_TIME 53 9167
TEST_TIME 54 9209
TEST_TIME 55 9178
TEST_TIME 56 9250
TEST_TIME 57 9244
TEST_TIME 58 9266
TEST_TIME 59 9270
TEST_TIME 60 9203
TEST_TIME 61 9326
TEST_TIME 62 9322
TEST_TIME 63 9429
TEST_TIME 64 9414
TEST_TIME 65 9426
TEST_TIME 66 9519
TEST_TIME 67 9555
TEST_TIME 68 9492
TEST_TIME 69 9400
TEST_TIME 70 9410
TEST_TIME 71 9367
TEST_TIME 72 9337
TEST_TIME 73 9356
TEST_TIME 74 9319
TEST_TIME 75 9267
TEST_TIME 76 9238
TEST_TIME 77 9223
TEST_TIME 78 9199
TEST_TIME 79 9187
TEST_TIME 80 9118
TEST_TIME 81 9084
TEST_TIME 82 9086
TEST_TIME 83 9078
TEST_TIME 84 9092
TEST_TIME 85 9087
TEST_TIME 86 9141
TEST_TIME 87 9139
TEST_TIME 88 9147
TEST_TIME 89 9189
TEST_TIME 90 9194
TEST_TIME 91 9230
TEST_TIME 92 9227
TEST_TIME 93 9294
TEST_TIME 94 9282
TEST_TIME 95 9303
TEST_TIME 96 9231
TEST_TIME 97 9194
TEST_TIME 98 9166
TEST_TIME 99 9121
TEST_TIME 100 9125
TEST_TIME 101 9069
TEST_TIME 102 9105
TEST_TIME 103 9082
TEST_TIME 104 9029
TEST_TIME 105 9001
TEST_TIME 106 9096
TEST_TIME 107 9090
TEST_TIME 108 9104
TEST_TIME 109 9100
TEST_TIME 110 9124
TEST_TIME 111 9181
TEST_TIME 112 9144
TEST_TIME 113 9176
TEST_TIME 114 9181
TEST_TIME 115 9249
TEST_TIME 116 9255
TEST_TIME 117 9244
TEST_TIME 118 9212
TEST_TIME 119 9174
TEST_TIME 120 9151

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2022 22:40:31 to 23-10-2022 22:40:35.

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 1534176 1534176 0 0.00 0 26331 6 0 0
doc_list_biud 1534176 1534176 0 0.00 0 30746 24 0 0
sp_add_invoice_to_stock 5275 5143 132 2.50 50 185832 18180 0 132
sp_cancel_adding_invoice 5444 5444 0 0.00 4 64696 754 0 0
sp_cancel_client_order 1893 1893 0 0.00 4 13661 394 0 0
sp_cancel_customer_reserve 538188 538188 0 0.00 0 30751 54 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 5633 84 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 7438 80 0 0
sp_cancel_supplier_invoice 6040 6029 11 0.18 2 22835 799 0 11
sp_cancel_supplier_order 386 386 0 0.00 14 982228 95330 0 0
sp_cancel_write_off 9405 9405 0 0.00 1 10648 102 0 0
sp_client_order 21399 21399 0 0.00 3 23096 346 0 0
sp_customer_reserve 471679 471679 0 0.00 0 74387 183 0 0
sp_fill_shopping_cart 33000 33000 0 0.00 1 14086 40 0 0
sp_fill_shopping_cart_clo_res 471672 471672 0 0.00 0 18842 14 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 32318 301 0 0
sp_kill_cost_storno 16005 16005 0 0.00 0 9022 48 0 0
sp_kill_qstorno_ret_qs2qd 1092196 1092196 0 0.00 0 30736 30 0 0
sp_kill_qty_storno 576845 576845 0 0.00 0 30746 64 0 0
sp_lock_selected_doc 21604 21604 0 0.00 0 7709 5 0 0
sp_make_cost_storno 28853 28853 0 0.00 0 26287 50 0 0
sp_multiply_rows_for_pdistr 28853 28853 0 0.00 0 9258 26 0 0
sp_multiply_rows_for_qdistr 21399 21399 0 0.00 0 16322 130 0 0
sp_pay_from_customer 7308 7308 0 0.00 4 15287 110 0 0
sp_pay_to_supplier 6045 6045 0 0.00 4 10266 117 0 0
sp_payment_common 13353 13353 0 0.00 1 15281 27 0 0
sp_qd_handle_on_cancel_clo 1893 1893 0 0.00 0 13656 349 0 0
sp_qd_handle_on_invoice_upd_sts 10579 10579 0 0.00 0 13466 294 0 0
sp_qd_handle_on_reserve_upd_sts 10365 10365 0 0.00 0 16623 62 0 0
sp_reserve_write_off 10365 10365 0 0.00 6 16654 171 0 0
sp_supplier_invoice 5746 5746 0 0.00 5 78958 3484 0 0
sp_supplier_order 5863 5863 0 0.00 5 60571 1318 0 0
srv_aggregate_perf_data 109 109 0 0.00 2734 387466 20768 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1089 134551 46780 0 0
srv_find_qd_qs_mism 1048262 1048262 0 0.00 0 11791 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 26 123106 1307 0 0
srv_make_money_saldo 755 755 0 0.00 1 1146 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 99011 588211 313713 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5143 5143 0 0.00 0 21931 173 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 25968 166 0 0
t$perf-norm:sp_cancel_client_order 1893 1893 0 0.00 0 3454 15 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 1350 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 5230 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1158 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 3503 33 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 34523 1636 0 0
t$perf-norm:sp_cancel_write_off 1116 1116 0 0.00 0 3313 14 0 0
t$perf-norm:sp_client_order 21399 21399 0 0.00 0 15253 21 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 2060 8 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 15356 14 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 11896 17 0 0
t$perf-norm:sp_reserve_write_off 10365 10365 0 0.00 0 4940 10 0 0
t$perf-norm:sp_supplier_invoice 5746 5746 0 0.00 0 14737 20 0 0
t$perf-norm:sp_supplier_order 5863 5863 0 0.00 0 15297 24 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 559 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 45 2 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 1079 21 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 1567 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10532 10268 264 2.50 0 7865 18 0 264
v_all_customers 38512 38512 0 0.00 0 37 1 0 0
v_all_suppliers 31526 31526 0 0.00 0 19 1 0 0
v_all_wares 42798 42798 0 0.00 0 60 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 2310 10 0 0
v_cancel_client_order 3786 3786 0 0.00 0 10371 8 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 3937 11 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 7951 11 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 1825 5 0 0
v_cancel_supplier_invoice 872 850 22 2.52 0 582 15 0 22
v_cancel_supplier_order 772 772 0 0.00 0 2752 6 0 0
v_cancel_write_off 2232 2232 0 0.00 0 1779 13 0 0
v_max_id_clo_ord 5862 5862 0 0.00 0 1224 1 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 11439 21 0 0
v_max_id_ord_sup 5739 5739 0 0.00 0 5046 4 0 0
v_max_non_paid_invoice 1883 1883 0 0.00 0 354 3 0 0
v_max_non_paid_realizn 4048 4048 0 0.00 0 1154 3 0 0
v_min_id_clo_ord 5862 5862 0 0.00 0 2640 5 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 6887 20 0 0
v_min_id_ord_sup 5739 5739 0 0.00 0 5166 5 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 6292 15 0 0
v_min_non_paid_realizn 7308 7308 0 0.00 0 8714 23 0 0
v_our_firm 10806 10806 0 0.00 0 17 1 0 0
v_reserve_write_off 20730 20730 0 0.00 0 12064 7 0 0
x_lock_depdocs_on_canc_invoice 11048 11048 0 0.00 0 12789 118 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 8093 341 0 0
x_make_qstorno_1000_1200 5863 5863 0 0.00 1 60501 1235 0 0
x_make_qstorno_1200_2000 5746 5746 0 0.00 2 73746 3341 0 0
x_make_qstorno_2100_3300 471679 471679 0 0.00 0 74386 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2022 22:40:36 to 23-10-2022 22:40:40.

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
20:08:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 24625 8192000000 9899289936 10207584256 987569136 10.758 100 90 180 0 1017064384 5737696 23757552
20:11:06 19226 8192000000 9934920048 10259144704 1025200096 11.122 100 86 172 0 1055300736 7923600 22177040
20:12:30 16913 8192000000 9986162704 10322014208 1071817024 11.569 100 88 176 0 1103299776 9743152 21739600
20:14:00 10341 8192000000 10035646128 10383761408 1123294944 12.058 100 83 166 0 1153462048 10801536 19365568
20:15:18 5854 8192000000 10081390992 10435969024 1166809904 12.467 100 87 172 0 1199074336 12745136 19519296
20:16:28 6073 8192000000 10123610672 10494586880 1214627680 12.912 100 82 164 0 1246361216 13645648 18087888
20:17:36 2302 8192000000 10163957664 10535546880 1243780560 13.181 100 91 177 1 1277347536 13977024 19589952
20:18:41 2176 8192000000 10190836064 10568974336 1273271440 13.452 100 84 168 1 1304943200 13349968 18321792
20:19:44 1085 8192000000 10218372608 10605436928 1299776832 13.693 100 84 167 0 1332479248 13956096 18746320
20:20:48 1316 8192000000 10227339856 10618105856 1318231856 13.861 100 76 153 0 1346727824 12175264 16320704
20:21:50 7092 8192000000 10211189792 10626146304 1316142240 13.842 100 28 57 0 1333145488 10877504 6125744
20:23:01 4676 8192000000 10223485024 10637242368 1328673824 13.955 100 34 68 0 1347856736 11749552 7433360
20:24:06 10027 8192000000 10213042112 10638258176 1330555328 13.972 100 19 38 0 1340927968 6152720 4219920
20:25:17 10126 8192000000 10226546496 10649858048 1340357504 14.061 100 20 40 1 1352193856 7392080 4444272
20:26:31 18364 8192000000 10233776544 10655399936 1342170976 14.077 100 37 74 0 1358092896 7858736 8063184
20:27:51 5964 8192000000 10213043024 10632540160 1299098320 13.687 97 27 52 0 1311380064 6268864 6012880
20:29:00 8647 8192000000 10222659040 10636574720 1297074000 13.669 97 25 50 0 1308746256 5793136 5879120
20:30:10 16908 8192000000 10191151872 10607677440 1278719216 13.501 97 15 28 2 1287239120 5584624 2935280
20:31:31 15760 8192000000 10121257120 10523832320 1128978224 12.112 88 17 34 1 1137520464 4092672 4449568
20:32:50 5393 8192000000 10127513968 10528378880 1224497760 13.003 99 20 40 0 1234951952 5496640 4957552
20:33:56 6970 8192000000 10153343104 10558779392 1261419872 13.343 100 28 56 0 1274088976 6107232 6561872
20:35:09 25309 8192000000 10206331696 10608570368 1294758080 13.648 100 24 48 1 1306868736 5969664 6140992
20:36:35 18291 8192000000 10192865712 10608574464 1304055696 13.732 100 30 57 1 1316169728 5825248 6288784
20:37:57 6048 8192000000 10198812208 10617819136 1314676560 13.828 100 28 54 0 1326226384 5279840 6269984
20:39:05 9846 8192000000 10237601936 10649239552 1335495056 14.017 100 48 94 0 1352709376 7344880 9869440
20:40:23 67459 8192000000 10259500928 10683596800 1353103456 14.175 100 34 66 1 1371288784 10308752 7876576
20:42:35 5089 8192000000 10287884000 10705317888 1374959216 14.371 100 55 110 2 1402599648 15392416 12248016
20:43:49 2094 8192000000 10318091728 10734149632 1388670736 14.494 100 61 122 1 1422579824 19782080 14127008
20:44:59 33480 8192000000 10312753056 10738114560 1397613456 14.574 100 40 78 0 1424964944 17753696 9597792
20:46:40 13499 8192000000 10324667680 10755203072 1411501600 14.697 100 33 66 0 1439243008 19509696 8231712
20:47:59 7853 8192000000 10343143840 10768244736 1418248256 14.757 100 53 105 1 1455110336 24094272 12767808
20:49:12 17151 8192000000 10356885664 10777419776 1420020624 14.773 100 63 122 1 1461266096 26982544 14262928
20:50:35 1088 8192000000 10370595952 10783473664 1424365472 14.811 100 84 167 0 1473678640 28696704 20616464
20:51:40 5186 8192000000 10384507408 10807271424 1432661472 14.885 100 73 145 2 1489084896 38802496 17620928
20:52:52 11361 8192000000 10355667216 10781687808 1406757200 14.655 99 65 129 1 1459222016 36230640 16234176
20:54:07 2835 8192000000 10373516336 10794602496 1421752784 14.788 100 82 159 1 1475716560 34756832 19206944
20:55:19 9152 8192000000 10354385856 10780573696 1408714768 14.673 99 50 99 0 1453555568 31928496 12912304
20:56:35 8256 8192000000 10357434624 10778484736 1407799232 14.664 100 44 89 0 1460525648 40619008 12107408
20:57:53 3112 8192000000 10349035168 10756784128 1379910816 14.416 100 90 175 2 1451344736 49601136 21832784
20:58:59 7520 8192000000 10315575984 10741374976 1378594928 14.404 100 36 72 0 1425043584 36617440 9831216
21:00:14 9788 8192000000 10309963584 10726985728 1357837888 14.218 99 52 103 0 1411780192 40023504 13918800
21:01:33 10323 8192000000 10299034544 10726854656 1359631904 14.234 100 37 74 0 1413591520 43486928 10472688
21:02:48 10591 8192000000 10284961168 10708516864 1341963568 14.075 99 37 73 0 1393641104 41743296 9934240
21:04:00 10861 8192000000 10286420048 10721398784 1352898368 14.174 100 23 46 0 1401309968 41773280 6638320
21:05:15 4706 8192000000 10310796336 10729148416 1357708256 14.217 100 68 136 0 1415609616 40613632 17287728
21:06:23 37657 8192000000 10276050848 10718875648 1333416944 13.998 98 20 40 1 1378806800 39805776 5584080
21:08:04 71979 8192000000 10266078768 10712629248 1348646576 14.135 99 29 56 0 1365251760 10441792 6163392
21:10:19 31580 8192000000 10290680496 10721693696 1369753808 14.325 99 50 103 0 1394899840 14073472 11072560
21:11:58 115083 8192000000 10274076928 10722934784 1345664992 14.108 98 18 36 0 1366477616 16455280 4357344
21:14:54 70080 8192000000 10275540752 10736893952 1375075136 14.372 100 11 20 0 1393825680 16541440 2209104
21:17:04 24092 8192000000 10290000480 10733858816 1387010448 14.479 100 36 70 1 1403771024 9082912 7677664
21:18:29 134551 8192000000 10296462720 10740637696 1365945376 14.291 98 24 49 0 1384825408 12699312 6180720
21:21:45 25453 8192000000 10298940816 10740342784 1375921088 14.380 99 53 104 0 1400598656 12352112 12325456
21:23:27 33789 8192000000 10297874032 10749210624 1393365424 14.536 100 40 77 1 1413633744 11058880 9209440
21:25:03 89384 8192000000 10302905952 10768924672 1386624896 14.476 99 14 28 0 1405422496 15236800 3560800
21:27:33 46793 8192000000 10299333760 10768486400 1405383216 14.643 100 12 24 0 1418346576 10224496 2738864
21:30:12 61274 8192000000 10302486048 10779406336 1408524928 14.671 100 17 34 0 1424058192 11575744 3957520
21:32:17 45668 8192000000 10309029680 10768912384 1403872608 14.629 100 20 41 0 1422376416 13254176 5249632
21:34:04 48851 8192000000 10310597968 10771337216 1410010704 14.684 100 17 34 0 1427459216 13388000 4060512
21:36:15 47110 8192000000 10322837040 10786160640 1409570816 14.680 100 38 78 0 1434476512 16004640 8901056
21:38:04 129611 8192000000 10305933328 10776817664 1376644800 14.387 98 20 40 0 1398434912 17390096 4400016
21:41:19 55873 8192000000 10294572000 10754179072 1333429648 13.998 95 13 26 0 1352664208 16821856 2412704
21:43:15 55112 8192000000 10276680480 10745712640 1360910720 14.246 99 20 40 0 1383841168 18385952 4544496
21:45:20 82951 8192000000 10272495392 10718363648 1294586048 13.646 94 28 53 0 1318626176 18177376 5862752
21:49:01 53521 8192000000 10280052448 10729107456 1351573968 14.162 98 44 87 1 1373885280 12588208 9723104
21:50:56 51056 8192000000 10268305456 10727329792 1358761504 14.226 99 17 34 0 1374300864 11578240 3961120
21:53:39 59033 8192000000 10252541040 10709884928 1338352800 14.043 98 21 42 1 1353900016 10876288 4670928
21:55:42 68144 8192000000 10253540976 10708828160 1349067200 14.139 99 10 19 0 1362439712 10898048 2474464
21:58:05 65504 8192000000 10243804960 10709475328 1342540752 14.080 99 9 18 0 1353245136 8839584 1864800
22:00:11 66801 8192000000 10244838976 10700455936 1292752592 13.629 95 11 22 0 1303056432 7758320 2545520
22:02:18 51539 8192000000 10262776544 10718695424 1361533936 14.251 100 15 30 0 1377182496 12284624 3363936
22:04:10 76864 8192000000 10259338416 10710282240 1313389056 13.817 96 13 26 0 1330666496 14229104 3048336
22:06:27 31565 8192000000 10276751568 10719727616 1352620800 14.171 99 39 75 2 1370919152 9847536 8450816
22:07:59 59592 8192000000 10296217856 10749571072 1379534752 14.412 100 20 40 0 1396780480 12748160 4497568
22:10:09 85321 8192000000 10300656176 10767245312 1398712224 14.584 100 8 16 0 1414157904 13669952 1775728
22:12:42 33662 8192000000 10324495024 10774663168 1403953216 14.630 100 21 42 0 1421181328 12648112 4580000
22:14:18 116407 8192000000 10300801728 10760650752 1368131808 14.310 97 27 52 1 1382140256 7616880 6391568
22:17:29 25085 8192000000 10302465120 10757677056 1392155616 14.525 99 25 50 0 1408644320 10838672 5650032
22:18:55 50742 8192000000 10288746800 10754752512 1380793232 14.424 99 8 16 0 1395004048 12422240 1788576
22:20:46 52782 8192000000 10280836928 10735083520 1336895472 14.029 96 18 35 0 1349290400 8281120 4113808
22:22:39 79377 8192000000 10280531008 10739769344 1320867632 13.885 95 15 30 0 1343724784 19572496 3284656
22:24:59 92438 8192000000 10282249680 10739425280 1339540032 14.053 97 15 30 0 1360296512 17442304 3314176
22:27:32 43847 8192000000 10287513120 10734497792 1352415024 14.169 98 25 50 0 1372112544 14086144 5611376
22:29:16 69712 8192000000 10270799664 10719944704 1351897264 14.165 99 10 19 1 1378280464 24349472 2033728
22:31:32 61208 8192000000 10287940784 10729218048 1313729056 13.820 95 34 68 0 1338234480 17539392 6966032
22:33:37 94103 8192000000 10269332688 10719899648 1302664512 13.719 95 12 24 0 1320531952 15393296 2474144
22:36:15 100617 8192000000 10262796560 10712788992 1339254720 14.051 98 27 54 0 1352369312 7867440 5247152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2022 22:40:42 to 23-10-2022 22:40:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2022 22:40:58 to 23-10-2022 22: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          242364
MON$OLDEST_ACTIVE               243901
MON$OLDEST_SNAPSHOT             243901
MON$NEXT_TRANSACTION            243901
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                       2024992
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Sun Oct 23 22:39:13 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Sun Oct 23 22:39:13 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:139: 
--- end of diff output ---

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

23.10.22 22: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
2022-10-23 22:41:04 No severe PSQL-related problems occured
23.10.22 22:41:05. End of report.