Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2023 16: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 15184136 kB
MemAvailable 30401036 kB
Buffers 0 kB
Cached 16504424 kB
SwapCached 3172 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-03-16 16:38:42
DTS_END                         2023-03-16 16:38:42.2500
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/49420
TRN_ID                          241223
ATT_ID                          722
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: 9217.59 20962 1106111
customer order: creation 172.87 339 20744
customer order: refuse 14.88 345 1786
order to supplier: creation 43.93 1242 5272
order to supplier: removal 3.31 92225 397
invoice (draft): creation 46.13 3391 5536
invoice (draft): removal 52.28 673 6273
invoice accept: apply 41.52 17710 4982
invoice accept: cancel 47.28 840 5674
customer reserve: creation 3873.86 174 464863
customer reserve: removal 4622.82 53 554738
realization accept: apply 83.79 174 10055
realization accept: cancel 85.76 109 10291
payment from customer: creation 59.27 100 7112
payment from customer: removal 5.57 90 668
payment to supplier: creation 48.97 128 5876
payment to supplier: removal 3.24 71 389
service: total inventory turnovers 4.97 1100 596
service: total monetary turnovers 6.37 31 764
service: aggregate perf. data 0.76 23212 91
service: refresh index statistics 0.03 277224 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-03-2023 16:39:58 to 16-03-2023 16:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3308
TEST_TIME 2 3524
TEST_TIME 3 4329
TEST_TIME 4 4640
TEST_TIME 5 5865
TEST_TIME 6 5823
TEST_TIME 7 5593
TEST_TIME 8 5694
TEST_TIME 9 5497
TEST_TIME 10 5884
TEST_TIME 11 5925
TEST_TIME 12 5943
TEST_TIME 13 5806
TEST_TIME 14 5971
TEST_TIME 15 5929
TEST_TIME 16 5988
TEST_TIME 17 6023
TEST_TIME 18 6280
TEST_TIME 19 6522
TEST_TIME 20 6544
TEST_TIME 21 7102
TEST_TIME 22 7559
TEST_TIME 23 7752
TEST_TIME 24 7987
TEST_TIME 25 8096
TEST_TIME 26 8406
TEST_TIME 27 8395
TEST_TIME 28 8710
TEST_TIME 29 8983
TEST_TIME 30 9422
TEST_TIME 31 9622
TEST_TIME 32 9711
TEST_TIME 33 9798
TEST_TIME 34 10010
TEST_TIME 35 9887
TEST_TIME 36 9817
TEST_TIME 37 9746
TEST_TIME 38 9756
TEST_TIME 39 9540
TEST_TIME 40 9675
TEST_TIME 41 9486
TEST_TIME 42 9422
TEST_TIME 43 9444
TEST_TIME 44 9393
TEST_TIME 45 9441
TEST_TIME 46 9536
TEST_TIME 47 9422
TEST_TIME 48 9420
TEST_TIME 49 9324
TEST_TIME 50 9378
TEST_TIME 51 9338
TEST_TIME 52 9282
TEST_TIME 53 9449
TEST_TIME 54 9444
TEST_TIME 55 9454
TEST_TIME 56 9422
TEST_TIME 57 9507
TEST_TIME 58 9559
TEST_TIME 59 9572
TEST_TIME 60 9648
TEST_TIME 61 9647
TEST_TIME 62 9642
TEST_TIME 63 9776
TEST_TIME 64 9648
TEST_TIME 65 9605
TEST_TIME 66 9512
TEST_TIME 67 9556
TEST_TIME 68 9430
TEST_TIME 69 9371
TEST_TIME 70 9403
TEST_TIME 71 9348
TEST_TIME 72 9260
TEST_TIME 73 9253
TEST_TIME 74 9224
TEST_TIME 75 9218
TEST_TIME 76 9165
TEST_TIME 77 9186
TEST_TIME 78 9186
TEST_TIME 79 9191
TEST_TIME 80 9161
TEST_TIME 81 9239
TEST_TIME 82 9248
TEST_TIME 83 9283
TEST_TIME 84 9281
TEST_TIME 85 9297
TEST_TIME 86 9420
TEST_TIME 87 9392
TEST_TIME 88 9461
TEST_TIME 89 9463
TEST_TIME 90 9474
TEST_TIME 91 9397
TEST_TIME 92 9308
TEST_TIME 93 9351
TEST_TIME 94 9347
TEST_TIME 95 9299
TEST_TIME 96 9287
TEST_TIME 97 9262
TEST_TIME 98 9262
TEST_TIME 99 9262
TEST_TIME 100 9292
TEST_TIME 101 9292
TEST_TIME 102 9334
TEST_TIME 103 9341
TEST_TIME 104 9368
TEST_TIME 105 9400
TEST_TIME 106 9421
TEST_TIME 107 9428
TEST_TIME 108 9426
TEST_TIME 109 9452
TEST_TIME 110 9453
TEST_TIME 111 9401
TEST_TIME 112 9374
TEST_TIME 113 9350
TEST_TIME 114 9330
TEST_TIME 115 9302
TEST_TIME 116 9264
TEST_TIME 117 9214
TEST_TIME 118 9271
TEST_TIME 119 9249
TEST_TIME 120 9228

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-03-2023 16:40:02 to 16-03-2023 16:40:05.

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 1536201 1536201 0 0.00 0 19104 6 0 0
doc_list_biud 1536195 1536195 0 0.00 0 21519 25 0 0
sp_add_invoice_to_stock 5151 4982 169 3.28 49 160490 17711 0 169
sp_cancel_adding_invoice 5674 5674 0 0.00 5 58986 841 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 7855 346 0 0
sp_cancel_customer_reserve 554738 554738 0 0.00 0 20342 53 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 6200 90 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 5293 71 0 0
sp_cancel_supplier_invoice 6287 6273 14 0.22 2 14838 674 0 14
sp_cancel_supplier_order 397 397 0 0.00 7 872041 92226 0 0
sp_cancel_write_off 10291 10291 0 0.00 1 16788 109 0 0
sp_client_order 20744 20744 0 0.00 3 28622 340 0 0
sp_customer_reserve 464863 464863 0 0.00 0 90878 174 0 0
sp_fill_shopping_cart 31543 31543 0 0.00 1 10599 36 0 0
sp_fill_shopping_cart_clo_res 464851 464851 0 0.00 0 16584 15 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 14337 294 0 0
sp_kill_cost_storno 17022 17022 0 0.00 0 9468 50 0 0
sp_kill_qstorno_ret_qs2qd 1126437 1126437 0 0.00 0 20324 29 0 0
sp_kill_qty_storno 594183 594183 0 0.00 0 21519 63 0 0
sp_lock_selected_doc 20831 20831 0 0.00 0 6773 6 0 0
sp_make_cost_storno 28012 28012 0 0.00 0 19095 50 0 0
sp_multiply_rows_for_pdistr 28012 28012 0 0.00 0 9378 26 0 0
sp_multiply_rows_for_qdistr 20744 20744 0 0.00 0 16615 146 0 0
sp_pay_from_customer 7112 7112 0 0.00 4 9392 101 0 0
sp_pay_to_supplier 5876 5876 0 0.00 4 20328 129 0 0
sp_payment_common 12988 12988 0 0.00 1 6526 26 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 7846 314 0 0
sp_qd_handle_on_invoice_upd_sts 10643 10643 0 0.00 0 21518 319 0 0
sp_qd_handle_on_reserve_upd_sts 10055 10055 0 0.00 0 15656 67 0 0
sp_reserve_write_off 10055 10055 0 0.00 6 17559 174 0 0
sp_supplier_invoice 5536 5536 0 0.00 6 146177 3392 0 0
sp_supplier_order 5272 5272 0 0.00 5 35341 1242 0 0
srv_aggregate_perf_data 91 91 0 0.00 2015 361795 23212 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2244 128620 44825 0 0
srv_find_qd_qs_mism 1058177 1058177 0 0.00 0 16764 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 31 43329 1100 0 0
srv_make_money_saldo 764 764 0 0.00 1 3960 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 175111 351946 277225 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4982 4982 0 0.00 0 17921 189 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 17678 158 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 6696 26 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 5612 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 3489 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 3694 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1672 18 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 34155 1686 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 4108 16 0 0
t$perf-norm:sp_client_order 20744 20744 0 0.00 0 9459 17 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1665 5 0 0
t$perf-norm:sp_pay_from_customer 7112 7112 0 0.00 0 9311 12 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 6189 14 0 0
t$perf-norm:sp_reserve_write_off 10055 10055 0 0.00 0 8813 14 0 0
t$perf-norm:sp_supplier_invoice 5536 5536 0 0.00 0 6549 22 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 7688 17 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 182 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 204 6 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 5914 35 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 2742 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10276 9938 338 3.28 0 14841 19 0 338
v_all_customers 37450 37450 0 0.00 0 26 0 0 0
v_all_suppliers 30120 30120 0 0.00 0 23 1 0 0
v_all_wares 41488 41488 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 211 6 0 0
v_cancel_client_order 3572 3572 0 0.00 0 2977 7 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 4991 12 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 2361 4 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 242 3 0 0
v_cancel_supplier_invoice 792 764 28 3.53 0 1816 18 0 28
v_cancel_supplier_order 794 794 0 0.00 0 20387 29 0 0
v_cancel_write_off 2070 2070 0 0.00 0 8866 15 0 0
v_max_id_clo_ord 5270 5270 0 0.00 0 461 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 3314 17 0 0
v_max_id_ord_sup 5528 5528 0 0.00 0 7485 5 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 3272 4 0 0
v_max_non_paid_realizn 3884 3884 0 0.00 0 3521 5 0 0
v_min_id_clo_ord 5270 5270 0 0.00 0 3549 7 0 0
v_min_id_clo_res 985 985 0 0.00 0 4187 9 0 0
v_min_id_ord_sup 5528 5528 0 0.00 0 2838 4 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 5732 20 0 0
v_min_non_paid_realizn 7112 7112 0 0.00 0 5393 18 0 0
v_our_firm 10494 10494 0 0.00 0 24 1 0 0
v_reserve_write_off 20110 20110 0 0.00 0 17513 7 0 0
x_lock_depdocs_on_canc_invoice 11565 11565 0 0.00 0 20318 104 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 4162 302 0 0
x_make_qstorno_1000_1200 5272 5272 0 0.00 1 35328 1179 0 0
x_make_qstorno_1200_2000 5536 5536 0 0.00 3 146073 3247 0 0
x_make_qstorno_2100_3300 464860 464860 0 0.00 0 90874 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2023 16:40:06 to 16-03-2023 16:40:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:46 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:47 21613 8192000000 9908698944 10220716032 987257808 10.755 100 82 164 0 1014469696 5712144 21499744
14:11:13 21463 8192000000 9946851264 10270752768 1031414512 11.182 100 89 178 0 1062878576 8621216 22842848
14:12:44 10126 8192000000 9975817792 10309783552 1064424352 11.499 100 79 158 0 1093764112 10097328 19242432
14:14:07 11063 8192000000 10035398816 10383683584 1122226832 12.048 100 82 164 0 1153248496 11419824 19601840
14:15:27 4625 8192000000 10073789456 10432741376 1165251984 12.452 100 77 154 0 1195905680 13064608 17589088
14:16:36 4955 8192000000 10119640000 10492792832 1212617504 12.893 100 79 155 0 1241333184 11041248 17674432
14:17:46 2051 8192000000 10158584080 10531045376 1244100512 13.184 100 89 175 2 1277710512 14577808 19032192
14:18:49 1594 8192000000 10192565360 10569981952 1270273536 13.424 100 92 184 1 1304995232 14715136 20006560
14:19:52 1598 8192000000 10199220000 10589978624 1293954192 13.640 100 77 154 0 1323122896 12274880 16893824
14:20:55 14701 8192000000 10197327488 10600099840 1300809024 13.703 100 34 68 0 1315919088 8022272 7087792
14:22:12 6105 8192000000 10198623888 10610266112 1310512864 13.791 100 21 40 1 1321475072 6064720 4897488
14:23:24 11712 8192000000 10204301040 10620891136 1318499008 13.863 100 13 26 0 1327203904 6022272 2682624
14:24:36 20377 8192000000 10210984592 10636169216 1325662432 13.928 100 31 60 1 1338251824 5049440 7539952
14:26:21 8339 8192000000 10218952512 10641907712 1328465216 13.953 100 24 47 0 1340143440 6658528 5019696
14:27:33 6441 8192000000 10204494480 10625568768 1305914528 13.749 98 24 48 0 1317138832 5459408 5764896
14:28:42 18680 8192000000 10205598832 10623549440 1288625232 13.592 97 17 33 2 1297951984 5863760 3462992
14:30:03 10595 8192000000 10208338112 10607656960 1297348816 13.671 100 63 127 1 1322691232 10336784 15005632
14:31:14 5155 8192000000 10186265952 10589093888 1273025776 13.449 99 40 80 0 1289034224 7280912 8727536
14:32:20 6184 8192000000 10134772080 10537852928 1245776160 13.199 100 14 28 1 1255012800 5678240 3558400
14:33:26 51754 8192000000 10154979568 10550157312 1229913584 13.053 97 33 67 0 1246750576 9060864 7776128
14:35:19 9729 8192000000 10166098272 10566311936 1246287664 13.204 97 30 60 0 1263515984 9955360 7272960
14:36:29 7515 8192000000 10194989552 10602246144 1296166768 13.660 100 19 38 0 1305404224 4666720 4570736
14:37:40 10775 8192000000 10200426464 10615111680 1307107104 13.760 100 13 26 0 1315609648 5795856 2706688
14:38:54 50188 8192000000 10234301040 10649800704 1330897312 13.975 100 31 62 0 1343764912 5695216 7172384
14:41:10 34687 8192000000 10258597472 10674868224 1352103712 14.166 100 51 102 1 1373850640 9815360 11931568
14:42:50 9215 8192000000 10264560480 10682810368 1358350144 14.223 100 51 102 0 1379692256 9873040 11469072
14:44:05 10524 8192000000 10292271088 10710487040 1378795488 14.406 100 54 106 1 1399898560 9206752 11896320
14:45:20 9341 8192000000 10307467552 10727161856 1396690384 14.566 100 50 96 0 1420199792 12480064 11029344
14:46:32 7062 8192000000 10306272544 10737451008 1399490656 14.590 100 40 78 0 1423635520 14464992 9679872
14:47:44 17007 8192000000 10304352336 10731118592 1371087424 14.337 98 38 74 1 1399030480 18730368 9212688
14:49:14 11049 8192000000 10325985840 10754158592 1409656464 14.681 100 44 88 0 1437264304 17260592 10347248
14:50:28 2244 8192000000 10335430000 10753871872 1405314256 14.642 100 68 134 0 1442666880 21235296 16117328
14:51:33 31836 8192000000 10312868592 10739212288 1363029632 14.265 97 40 78 2 1398286224 25683600 9572992
14:53:31 17154 8192000000 10313220448 10737786880 1386416944 14.474 100 44 88 0 1421144896 24515184 10212768
14:54:54 8679 8192000000 10323793712 10739744768 1387334944 14.482 100 53 106 0 1431956608 31610384 13011280
14:56:07 8265 8192000000 10333605424 10755485696 1385749088 14.468 99 48 94 0 1435598800 37763344 12086368
14:57:19 3642 8192000000 10349221760 10762903552 1393223024 14.535 100 70 135 0 1451694928 41217776 17254128
14:58:28 13297 8192000000 10328055248 10735947776 1362152096 14.257 99 64 128 0 1430652848 51951136 16549616
14:59:49 3375 8192000000 10338943904 10741264384 1369115168 14.319 100 73 145 2 1437915680 51569104 17231408
15:00:55 16435 8192000000 10293716304 10721619968 1349991264 14.147 99 26 52 0 1398809280 41666624 7151392
15:02:16 4219 8192000000 10310641200 10724859904 1355002672 14.192 100 46 91 0 1411861408 45602048 11256688
15:03:26 37442 8192000000 10309856848 10740510720 1369196976 14.320 100 29 58 0 1419411872 42469552 7745344
15:05:13 6560 8192000000 10299705392 10731671552 1367502864 14.305 100 33 63 0 1414825696 39069120 8253712
15:06:23 27521 8192000000 10298780192 10736218112 1351680464 14.163 100 25 50 1 1401602992 43127968 6794560
15:07:53 40417 8192000000 10267187744 10695983104 1313756832 13.820 97 13 26 0 1350683744 33641632 3285280
15:09:35 46325 8192000000 10288480512 10721079296 1342226768 14.077 98 12 24 0 1357423760 12643760 2553232
15:11:22 64876 8192000000 10286064256 10739535872 1362337872 14.258 99 6 12 0 1376221856 12606368 1277616
15:13:32 14310 8192000000 10300165312 10736672768 1380149888 14.418 100 21 43 0 1396337568 11590576 4597104
15:14:48 98454 8192000000 10300423824 10750341120 1380797376 14.424 99 33 66 0 1401710976 12699920 8213680
15:18:18 51864 8192000000 10262115296 10719137792 1344229296 14.096 97 9 18 1 1361257264 14927184 2100784
15:21:18 31131 8192000000 10321565456 10761342976 1403391952 14.625 100 48 94 1 1427405408 12853776 11159680
15:22:53 44641 8192000000 10294063952 10752798720 1389776080 14.504 99 22 44 0 1408556912 13297408 5483424
15:24:39 43902 8192000000 10300430080 10765234176 1400065344 14.596 100 7 14 0 1412629680 10883136 1681200
15:26:24 37351 8192000000 10287887040 10740346880 1376477024 14.385 98 16 30 0 1389207008 9105360 3624624
15:28:12 37547 8192000000 10297537152 10759368704 1396099824 14.560 100 30 60 0 1418143680 14842960 7200896
15:30:00 31446 8192000000 10303395984 10761519104 1393237936 14.535 100 13 26 0 1408809232 12401040 3170256
15:32:05 26482 8192000000 10281506880 10732240896 1358034416 14.220 98 16 34 0 1371585120 9844320 3706384
15:33:34 58476 8192000000 10301424896 10752274432 1384105568 14.453 100 23 46 0 1402995552 13759344 5130640
15:35:41 76711 8192000000 10281468144 10747363328 1313154672 13.815 94 18 36 0 1332168976 14777104 4237200
15:37:58 33577 8192000000 10271653936 10720129024 1350272832 14.150 98 24 47 1 1366610112 11034704 5302576
15:39:32 85440 8192000000 10294680176 10748366848 1333926016 14.003 96 12 24 0 1351461088 15253680 2281392
15:42:02 80435 8192000000 10252366192 10695634944 1306018256 13.750 96 34 68 0 1322984512 9179264 7786992
15:44:32 49783 8192000000 10250089920 10707988480 1351578224 14.162 100 11 22 0 1366187952 12297504 2312224
15:46:28 83588 8192000000 10250673408 10714812416 1317398208 13.853 97 6 12 0 1335375440 16145808 1831424
15:49:05 45971 8192000000 10243504352 10697871360 1349349824 14.142 100 24 48 0 1362697088 7290080 6057184
15:50:52 87239 8192000000 10235804576 10684698624 1257829504 13.310 93 21 42 0 1274556560 11802752 4924304
15:53:44 41439 8192000000 10236391952 10683162624 1320164144 13.878 98 17 32 0 1334292784 10383696 3744944
15:55:26 77540 8192000000 10241453584 10705137664 1334317504 14.006 99 11 22 0 1351536976 15056672 2162800
15:58:01 60483 8192000000 10255955440 10700668928 1316613984 13.846 97 26 52 0 1345545648 23217552 5714112
16:00:15 45084 8192000000 10267403488 10719248384 1331419392 13.980 98 15 30 0 1351290416 16504176 3366848
16:02:08 16613 8192000000 10279778192 10718220288 1350214272 14.149 99 47 94 0 1371098608 10498064 10386272
16:03:25 71113 8192000000 10269486432 10732314624 1337286064 14.033 98 31 62 0 1369915152 25223216 7405872
16:05:37 19463 8192000000 10270287488 10710618112 1335944448 14.021 98 27 54 0 1350265488 8519504 5801536
16:06:57 122341 8192000000 10286299024 10735742976 1335261648 14.015 97 11 22 0 1349115600 11601232 2252720
16:09:59 100742 8192000000 10280149552 10747039744 1388253376 14.490 100 34 67 1 1405320272 9008304 8058592
16:13:05 73681 8192000000 10302228032 10765443072 1396426448 14.563 100 14 28 0 1411721360 12043808 3251104
16:15:19 60377 8192000000 10296208032 10763288576 1387293920 14.482 99 10 20 0 1397966704 8383008 2289776
16:17:20 51371 8192000000 10301414880 10756853760 1374629552 14.369 98 30 60 0 1392847440 10864960 7352928
16:19:19 78508 8192000000 10312081232 10782945280 1387660496 14.485 99 19 36 0 1405267680 12991360 4615824
16:21:40 48442 8192000000 10296845600 10757656576 1377118384 14.391 99 18 35 1 1399371696 18711344 3541968
16:23:29 49682 8192000000 10274010896 10736365568 1347800640 14.128 98 29 58 0 1365936144 11082704 7052800
16:25:22 81593 8192000000 10288179120 10745786368 1341595360 14.072 97 15 30 0 1357515696 12728576 3191760
16:27:50 128620 8192000000 10258896304 10713251840 1325056336 13.922 96 28 57 0 1347456976 15674448 6726192
16:31:03 27522 8192000000 10257299968 10696138752 1347305024 14.123 99 33 67 1 1362666176 8210960 7150192
16:32:31 65509 8192000000 10235332384 10697072640 1315227664 13.833 97 8 16 0 1326141616 9129536 1784416
16:35:12 91743 8192000000 10250821872 10720329728 1353853472 14.182 100 21 42 1 1370067440 10847968 5366000
16:37:49 57068 8192000000 9363750960 9696260096 477525328 5.508 36 8 16 0 491347840 11998448 1824064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-03-2023 16:40:12 to 16-03-2023 16:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2023 16:40:28 to 16-03-2023 16:40:30.

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          240068
MON$OLDEST_ACTIVE               241585
MON$OLDEST_SNAPSHOT             241585
MON$NEXT_TRANSACTION            241585
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                       2036704
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:338: 
:339: mscs-test-161.budzdorov.ru    Thu Mar 16 16:38:48 2023
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
--- end of diff output ---

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

16.03.23 16:40:31. 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-03-16 16:40:34 No severe PSQL-related problems occured
16.03.23 16:40:35. End of report.