Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.2023 19: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 15497280 kB
MemAvailable 30460136 kB
Buffers 0 kB
Cached 16237612 kB
SwapCached 3176 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-18 19:38:37
DTS_END                         2023-02-18 19:38:37.4630
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/46918
TRN_ID                          232132
ATT_ID                          667
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: 9225.19 25762 1107023
customer order: creation 163.36 402 19603
customer order: refuse 14.09 382 1691
order to supplier: creation 44.29 1313 5315
order to supplier: removal 3.33 104571 400
invoice (draft): creation 44.34 3302 5321
invoice (draft): removal 52.94 856 6353
invoice accept: apply 40.97 17038 4916
invoice accept: cancel 47.78 761 5734
customer reserve: creation 3815.62 166 457874
customer reserve: removal 4716.98 57 566038
realization accept: apply 79.13 186 9496
realization accept: cancel 79.09 97 9491
payment from customer: creation 55.83 142 6699
payment from customer: removal 5.50 96 660
payment to supplier: creation 46.73 130 5608
payment to supplier: removal 3.63 81 435
service: total inventory turnovers 4.31 1358 517
service: total monetary turnovers 6.38 36 766
service: aggregate perf. data 0.85 17263 102
service: refresh index statistics 0.03 367008 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-02-2023 19:40:09 to 18-02-2023 19:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5138
TEST_TIME 2 4537
TEST_TIME 3 5506
TEST_TIME 4 5162
TEST_TIME 5 5503
TEST_TIME 6 5646
TEST_TIME 7 6114
TEST_TIME 8 5896
TEST_TIME 9 6137
TEST_TIME 10 6174
TEST_TIME 11 6098
TEST_TIME 12 6318
TEST_TIME 13 6730
TEST_TIME 14 7120
TEST_TIME 15 7265
TEST_TIME 16 7294
TEST_TIME 17 7701
TEST_TIME 18 7717
TEST_TIME 19 8371
TEST_TIME 20 8586
TEST_TIME 21 8633
TEST_TIME 22 8818
TEST_TIME 23 9014
TEST_TIME 24 9427
TEST_TIME 25 9335
TEST_TIME 26 9186
TEST_TIME 27 9478
TEST_TIME 28 10693
TEST_TIME 29 10676
TEST_TIME 30 10638
TEST_TIME 31 10788
TEST_TIME 32 10461
TEST_TIME 33 10417
TEST_TIME 34 10319
TEST_TIME 35 10185
TEST_TIME 36 10028
TEST_TIME 37 9877
TEST_TIME 38 9853
TEST_TIME 39 9693
TEST_TIME 40 9593
TEST_TIME 41 9696
TEST_TIME 42 9509
TEST_TIME 43 9550
TEST_TIME 44 9595
TEST_TIME 45 9576
TEST_TIME 46 9490
TEST_TIME 47 9395
TEST_TIME 48 9239
TEST_TIME 49 9159
TEST_TIME 50 9114
TEST_TIME 51 8993
TEST_TIME 52 8982
TEST_TIME 53 9006
TEST_TIME 54 9062
TEST_TIME 55 9079
TEST_TIME 56 9052
TEST_TIME 57 9190
TEST_TIME 58 9251
TEST_TIME 59 9254
TEST_TIME 60 9222
TEST_TIME 61 9330
TEST_TIME 62 9396
TEST_TIME 63 9356
TEST_TIME 64 9519
TEST_TIME 65 9556
TEST_TIME 66 9506
TEST_TIME 67 9404
TEST_TIME 68 9413
TEST_TIME 69 9374
TEST_TIME 70 9338
TEST_TIME 71 9303
TEST_TIME 72 9277
TEST_TIME 73 9212
TEST_TIME 74 9180
TEST_TIME 75 9306
TEST_TIME 76 9253
TEST_TIME 77 9319
TEST_TIME 78 9301
TEST_TIME 79 9266
TEST_TIME 80 9247
TEST_TIME 81 9372
TEST_TIME 82 9352
TEST_TIME 83 9342
TEST_TIME 84 9475
TEST_TIME 85 9453
TEST_TIME 86 9399
TEST_TIME 87 9369
TEST_TIME 88 9329
TEST_TIME 89 9271
TEST_TIME 90 9249
TEST_TIME 91 9314
TEST_TIME 92 9284
TEST_TIME 93 9243
TEST_TIME 94 9208
TEST_TIME 95 9180
TEST_TIME 96 9174
TEST_TIME 97 9220
TEST_TIME 98 9219
TEST_TIME 99 9241
TEST_TIME 100 9297
TEST_TIME 101 9305
TEST_TIME 102 9356
TEST_TIME 103 9320
TEST_TIME 104 9343
TEST_TIME 105 9390
TEST_TIME 106 9387
TEST_TIME 107 9410
TEST_TIME 108 9443
TEST_TIME 109 9495
TEST_TIME 110 9411
TEST_TIME 111 9343
TEST_TIME 112 9356
TEST_TIME 113 9307
TEST_TIME 114 9258
TEST_TIME 115 9241
TEST_TIME 116 9262
TEST_TIME 117 9252
TEST_TIME 118 9202
TEST_TIME 119 9192
TEST_TIME 120 9230

Performance per minute, chart


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

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 1530218 1530218 0 0.00 0 32798 6 0 0
doc_list_biud 1530218 1530218 0 0.00 0 35754 27 0 0
sp_add_invoice_to_stock 5035 4916 119 2.36 46 159315 17038 0 119
sp_cancel_adding_invoice 5734 5734 0 0.00 4 55272 761 0 0
sp_cancel_client_order 1691 1691 0 0.00 4 13599 382 0 0
sp_cancel_customer_reserve 566038 566038 0 0.00 0 22402 57 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 2 6222 96 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 2394 82 0 0
sp_cancel_supplier_invoice 6356 6353 3 0.04 2 35762 856 0 3
sp_cancel_supplier_order 400 400 0 0.00 6 997865 104572 0 0
sp_cancel_write_off 9491 9491 0 0.00 1 14858 98 0 0
sp_client_order 19603 19603 0 0.00 3 27758 402 0 0
sp_customer_reserve 457874 457874 0 0.00 0 69524 167 0 0
sp_fill_shopping_cart 30234 30234 0 0.00 1 13839 39 0 0
sp_fill_shopping_cart_clo_res 457871 457871 0 0.00 0 10874 14 0 0
sp_get_clo_for_invoice 4911 4911 0 0.00 0 24684 380 0 0
sp_kill_cost_storno 16320 16320 0 0.00 0 13268 52 0 0
sp_kill_qstorno_ret_qs2qd 1148320 1148320 0 0.00 0 35750 31 0 0
sp_kill_qty_storno 604114 604114 0 0.00 0 35752 68 0 0
sp_lock_selected_doc 19917 19917 0 0.00 0 7107 4 0 0
sp_make_cost_storno 26714 26714 0 0.00 0 32796 48 0 0
sp_multiply_rows_for_pdistr 26714 26714 0 0.00 0 5999 26 0 0
sp_multiply_rows_for_qdistr 19603 19603 0 0.00 0 13849 174 0 0
sp_pay_from_customer 6699 6699 0 0.00 4 32803 143 0 0
sp_pay_to_supplier 5608 5608 0 0.00 4 18582 130 0 0
sp_payment_common 12307 12307 0 0.00 1 10915 28 0 0
sp_qd_handle_on_cancel_clo 1691 1691 0 0.00 0 13590 346 0 0
sp_qd_handle_on_invoice_upd_sts 10645 10645 0 0.00 0 22991 323 0 0
sp_qd_handle_on_reserve_upd_sts 9496 9496 0 0.00 0 17511 72 0 0
sp_reserve_write_off 9496 9496 0 0.00 6 21549 187 0 0
sp_supplier_invoice 5321 5321 0 0.00 6 73456 3303 0 0
sp_supplier_order 5315 5315 0 0.00 4 33416 1313 0 0
srv_aggregate_perf_data 102 102 0 0.00 2336 153214 17263 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1844 155405 45550 0 0
srv_find_qd_qs_mism 1060469 1060469 0 0.00 0 22541 5 0 0
srv_make_invnt_saldo 517 517 0 0.00 50 118591 1359 0 0
srv_make_money_saldo 766 766 0 0.00 1 5285 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 130266 498643 367008 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 0 15548 165 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 3161 97 0 0
t$perf-norm:sp_cancel_client_order 1691 1691 0 0.00 0 9172 21 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 823 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 1596 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 2930 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 348 348 0 0.00 0 8505 43 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 26755 1805 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 2356 11 0 0
t$perf-norm:sp_client_order 19603 19603 0 0.00 0 8752 16 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 2161 6 0 0
t$perf-norm:sp_pay_from_customer 6699 6699 0 0.00 0 10183 14 0 0
t$perf-norm:sp_pay_to_supplier 5608 5608 0 0.00 0 13793 15 0 0
t$perf-norm:sp_reserve_write_off 9496 9496 0 0.00 0 9882 12 0 0
t$perf-norm:sp_supplier_invoice 5321 5321 0 0.00 0 10704 22 0 0
t$perf-norm:sp_supplier_order 5315 5315 0 0.00 0 7340 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 3993 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1055 22 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 1934 23 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 1196 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10060 9822 238 2.36 0 6860 22 0 238
v_all_customers 35574 35574 0 0.00 0 22 1 0 0
v_all_suppliers 29252 29252 0 0.00 0 23 1 0 0
v_all_wares 39206 39206 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 5260 14 0 0
v_cancel_client_order 3382 3382 0 0.00 0 5031 11 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 6206 13 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 3102 9 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2279 15 0 0
v_cancel_supplier_invoice 702 696 6 0.85 0 3106 22 0 6
v_cancel_supplier_order 800 800 0 0.00 0 3863 14 0 0
v_cancel_write_off 1920 1920 0 0.00 0 4990 18 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 591 1 0 0
v_max_id_clo_res 952 952 0 0.00 0 4419 15 0 0
v_max_id_ord_sup 5316 5316 0 0.00 0 1755 3 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 865 3 0 0
v_max_non_paid_realizn 3602 3602 0 0.00 0 3699 4 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 4620 7 0 0
v_min_id_clo_res 952 952 0 0.00 0 1386 8 0 0
v_min_id_ord_sup 5316 5316 0 0.00 0 8929 9 0 0
v_min_non_paid_invoice 5608 5608 0 0.00 0 9672 17 0 0
v_min_non_paid_realizn 6699 6699 0 0.00 0 10203 22 0 0
v_our_firm 9826 9826 0 0.00 0 20 1 0 0
v_reserve_write_off 18992 18992 0 0.00 0 9429 10 0 0
x_lock_depdocs_on_canc_invoice 11739 11739 0 0.00 0 18622 126 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 5729 372 0 0
x_make_qstorno_1000_1200 5315 5315 0 0.00 1 33405 1234 0 0
x_make_qstorno_1200_2000 5321 5321 0 0.00 3 69818 3162 0 0
x_make_qstorno_2100_3300 457874 457874 0 0.00 0 69519 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-02-2023 19:40:17 to 18-02-2023 19:40:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:41 7 8192000000 8799303680 8810692608 5307904 0.064 2 1 2 0 5407504 56816 42784
17:09:42 15643 8192000000 9888986656 10192764928 969331488 10.580 100 88 176 0 999100560 5843264 23925808
17:11:03 14782 8192000000 9935899040 10256244736 1022934784 11.100 100 86 172 0 1052685840 7933216 21817840
17:12:32 14584 8192000000 9998616464 10328637440 1068565360 11.538 100 88 176 0 1100395824 10202800 21627664
17:13:51 14720 8192000000 10032257184 10378883072 1116803152 11.997 100 75 150 0 1145362688 10917472 17642064
17:15:09 6019 8192000000 10070868128 10427449344 1158155056 12.386 100 77 154 0 1187641440 11793440 17692944
17:16:18 4310 8192000000 10109162384 10474823680 1195671792 12.736 100 78 154 0 1225985456 13347456 16966208
17:17:25 2644 8192000000 10142592480 10513428480 1228838160 13.043 100 80 160 0 1259560544 13604208 17118176
17:18:31 3550 8192000000 10164081552 10542153728 1250402016 13.242 100 79 158 0 1281777504 14486208 16889280
17:19:37 1355 8192000000 10206413632 10588053504 1288509040 13.591 100 88 173 0 1321877520 14374848 18993632
17:20:40 26331 8192000000 10201453248 10602151936 1300305232 13.698 100 41 80 0 1316573760 6633664 9634864
17:22:25 11215 8192000000 10190122160 10609385472 1308957296 13.777 100 28 55 2 1321995312 7102800 5935216
17:23:40 8869 8192000000 10200632912 10619699200 1317196928 13.851 100 18 35 0 1326267856 5493008 3577920
17:24:52 7195 8192000000 10214114672 10633846784 1329530960 13.963 100 18 36 1 1340138048 6551680 4055408
17:26:00 5434 8192000000 10214790080 10635890688 1333509536 13.999 100 21 40 0 1344122992 5861392 4752064
17:27:06 27808 8192000000 10221412144 10649149440 1335799664 14.020 100 52 103 0 1357977728 9668928 12509136
17:28:36 8254 8192000000 10222038336 10646409216 1335664352 14.018 100 23 46 0 1346722848 5774096 5284400
17:29:47 11070 8192000000 10199822144 10616389632 1303245520 13.725 99 20 41 0 1317166160 9614640 4306000
17:31:02 58683 8192000000 10143891984 10548633600 1200023680 12.777 94 42 85 0 1215765776 5223248 10518848
17:33:02 5963 8192000000 10117194240 10513010688 1208049360 12.851 97 23 46 1 1219202928 5796864 5356704
17:34:09 9021 8192000000 10146069648 10552352768 1257789488 13.310 100 19 37 0 1267407984 5333472 4285024
17:35:23 10683 8192000000 10171217248 10587353088 1286605168 13.573 100 18 34 0 1296302528 5863600 3833760
17:36:34 19530 8192000000 10176496624 10596384768 1294571952 13.646 100 25 51 0 1305950352 5066848 6311552
17:37:56 4837 8192000000 10198385952 10607915008 1303232720 13.725 100 25 50 1 1314916480 6080016 5603744
17:39:05 28810 8192000000 10228693360 10644381696 1332330048 13.988 100 34 67 0 1345618080 6091776 7196256
17:40:38 26857 8192000000 10265972112 10677231616 1349135232 14.140 100 48 97 0 1374521920 14313616 11073072
17:42:06 28248 8192000000 10276799088 10697867264 1369165728 14.320 100 43 86 0 1395954272 16749728 10038816
17:43:39 2352 8192000000 10290497184 10711404544 1370875920 14.335 100 49 98 0 1401814784 19566624 11372240
17:44:49 18505 8192000000 10290504800 10714562560 1377530400 14.394 100 47 94 0 1412682736 24059280 11093056
17:46:11 5678 8192000000 10307191216 10727436288 1386671552 14.476 100 63 124 1 1422938160 21654400 14612208
17:47:21 1867 8192000000 10330872832 10745188352 1399192544 14.588 100 78 151 1 1442984368 25375200 18416624
17:48:26 13476 8192000000 10326961488 10750644224 1398209296 14.579 100 53 106 0 1439832496 28592592 13030608
17:49:48 7477 8192000000 10333891248 10758033408 1399692176 14.592 100 54 106 3 1445814560 32700304 13422080
17:51:02 3545 8192000000 10329465584 10749022208 1387224272 14.481 100 61 121 1 1438161696 35805520 15131904
17:52:09 5702 8192000000 10335870448 10759741440 1399999408 14.595 100 57 113 0 1447077648 32189568 14888672
17:53:23 3227 8192000000 10333868176 10762547200 1391589792 14.520 99 53 105 0 1444271456 40321296 12360368
17:54:34 6496 8192000000 10341421104 10757787648 1390156608 14.507 100 67 134 0 1451270816 44124288 16989920
17:55:53 5269 8192000000 10346911040 10766651392 1386349504 14.473 100 64 125 1 1449686320 47713824 15622992
17:57:03 4964 8192000000 10361765040 10779672576 1397782624 14.575 100 60 119 2 1467351616 54355728 15213264
17:58:15 1843 8192000000 10343268256 10755850240 1385224816 14.463 100 83 162 1 1449060224 44222944 19612464
17:59:23 19037 8192000000 10318451968 10744729600 1379168880 14.409 100 32 64 1 1426252800 38326512 8757408
18:00:50 12913 8192000000 10337503408 10761039872 1385860064 14.469 100 34 68 1 1443393360 47952768 9580528
18:02:06 3401 8192000000 10328072832 10753937408 1372544208 14.350 99 54 105 1 1430181184 44116336 13520640
18:03:13 35154 8192000000 10305901584 10741858304 1360205008 14.239 99 23 46 0 1410388784 44061824 6121952
18:04:53 14656 8192000000 10286406784 10712662016 1316826224 13.848 99 30 59 1 1380283232 54742144 8714864
18:06:12 26557 8192000000 10293364112 10742329344 1335975616 14.021 99 20 41 0 1393318064 51773088 5569360
18:07:44 3382 8192000000 10290427408 10714185728 1360951600 14.246 100 67 127 4 1389728000 13202768 15573632
18:08:51 13994 8192000000 10275060768 10720096256 1358930944 14.228 99 32 58 0 1376493968 10979936 6583088
18:10:06 58596 8192000000 10251715120 10685972480 1330153632 13.969 98 36 70 2 1349595056 10156032 9285392
18:12:06 62112 8192000000 10262282192 10711109632 1352705728 14.172 99 26 50 0 1365136368 7414144 5016496
18:14:29 135062 8192000000 10288067776 10739953664 1327170464 13.942 96 18 36 0 1342719984 11732608 3816912
18:17:45 58718 8192000000 10302349568 10758262784 1391246224 14.517 100 15 30 0 1416384784 21605088 3533472
18:19:59 139892 8192000000 10287266032 10747867136 1372419136 14.349 99 15 30 0 1397937808 22248528 3270144
18:23:20 46397 8192000000 10278460624 10728968192 1353136016 14.176 98 37 68 2 1373175488 11404416 8635056
18:25:10 80349 8192000000 10271500960 10738790400 1382144224 14.436 100 14 29 0 1397726112 12734096 2847792
18:27:31 9847 8192000000 10283570464 10742366208 1388752272 14.495 100 19 38 0 1400372016 7657184 3962560
18:28:43 77360 8192000000 10295184304 10761715712 1385056944 14.462 99 12 24 0 1399955360 12297360 2601056
18:31:08 52644 8192000000 10300711872 10758156288 1368190496 14.311 98 23 46 0 1384165472 11060896 4914080
18:33:04 59379 8192000000 10306811440 10767634432 1390595280 14.511 99 30 58 0 1412709504 15358192 6756032
18:35:08 52007 8192000000 10312883712 10778984448 1390440416 14.510 99 19 38 0 1410826240 15992464 4393360
18:37:00 47211 8192000000 10287366256 10747850752 1343106512 14.085 96 27 54 0 1360977936 11849552 6021872
18:38:48 85672 8192000000 10294604768 10751397888 1362522448 14.260 98 25 51 2 1386703808 18630352 5551008
18:41:21 68124 8192000000 10312656624 10765070336 1376467440 14.385 99 20 40 0 1396327184 15725936 4133808
18:43:30 74625 8192000000 10283981344 10752212992 1344656976 14.099 97 8 16 0 1364116336 18012240 1447120
18:45:45 49706 8192000000 10269355072 10718056448 1346739904 14.118 98 36 71 0 1366083728 10872336 8471488
18:47:44 44684 8192000000 10264826160 10726248448 1368280992 14.312 100 7 14 0 1377301408 7274208 1746208
18:49:35 14938 8192000000 10256138752 10696601600 1333343616 13.997 98 44 88 4 1352010448 8667168 9999664
18:50:51 51096 8192000000 10242460672 10681552896 1280867776 13.521 95 16 32 0 1297169360 12930784 3370800
18:52:42 93622 8192000000 10260124880 10704527360 1323676368 13.910 98 20 40 0 1347177120 18104864 5395888
18:55:19 62735 8192000000 10250406272 10699132928 1309464256 13.781 97 16 32 0 1321251584 8466464 3320864
18:57:34 12216 8192000000 10279313888 10706984960 1354168112 14.185 100 49 96 1 1374140144 8682800 11289232
18:58:47 80814 8192000000 10276299376 10738262016 1353169312 14.176 99 28 56 0 1379428208 19978256 6280640
19:01:08 155405 8192000000 10270498784 10725208064 1319231488 13.870 96 11 22 0 1336162832 14614144 2317200
19:04:46 113556 8192000000 10267931456 10718429184 1318145712 13.860 96 17 34 0 1332702704 10754016 3802976
19:07:43 6358 8192000000 10259936352 10691743744 1341132928 14.068 98 49 92 1 1359160128 7108144 10919056
19:08:57 76105 8192000000 10280441760 10743275520 1377174704 14.391 100 9 18 0 1391387424 12648384 1564336
19:11:16 45357 8192000000 10276004448 10737291264 1380740176 14.423 100 16 32 0 1392155424 7501408 3913840
19:13:10 49986 8192000000 10290040784 10749173760 1365184400 14.284 98 22 40 2 1377916624 8140016 4592208
19:15:01 48654 8192000000 10293944800 10747555840 1369292832 14.321 98 18 36 1 1387962176 14441360 4227984
19:16:53 73119 8192000000 10303914528 10759245824 1376773232 14.388 99 15 30 0 1402588720 21918512 3896976
19:19:10 35197 8192000000 10307388576 10751270912 1383843936 14.451 99 27 54 0 1402083280 12330368 5908976
19:20:49 65500 8192000000 10300419744 10747666432 1357054288 14.211 97 19 38 0 1371988816 10496448 4438080
19:23:03 67792 8192000000 10287937232 10749231104 1372303024 14.348 99 43 87 2 1392132992 9996112 9833856
19:25:18 83526 8192000000 10297047360 10748977152 1335308544 14.015 96 14 27 0 1352004928 14041504 2654880
19:27:44 118571 8192000000 10291177456 10744279040 1383375200 14.447 100 24 48 0 1402924608 14257920 5291488
19:30:55 43486 8192000000 10260529104 10724012032 1321931296 13.894 95 2 4 0 1328778464 6285216 561952
19:32:50 51608 8192000000 10290077616 10749153280 1379041248 14.408 99 9 18 0 1394928192 13368608 2518336
19:34:45 50271 8192000000 10286837456 10751598592 1378074784 14.399 100 14 28 0 1396660784 15167568 3418432
19:36:42 64972 8192000000 10275219072 10727854080 1360992688 14.246 99 19 38 0 1382475744 17539216 3943840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-02-2023 19:40:23 to 18-02-2023 19:40:39.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-02-2023 19:40:40 to 18-02-2023 19:40:42.

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          231624
MON$OLDEST_ACTIVE               232282
MON$OLDEST_SNAPSHOT             232282
MON$NEXT_TRANSACTION            232282
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                       2012032
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 ---
:179: 
:180: mscs-test-161.budzdorov.ru    Sat Feb 18 19:38:44 2023
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:182: 
:183: 
:184: mscs-test-161.budzdorov.ru    Sat Feb 18 19:38:47 2023
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Sat Feb 18 19:38:47 2023
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Sat Feb 18 19:38:47 2023
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Sat Feb 18 19:38:47 2023
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
--- end of diff output ---

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

18.02.23 19:40:43. 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-18 19:40:45 No severe PSQL-related problems occured
18.02.23 19:40:47. End of report.