Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2022 22: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 15593000 kB
MemAvailable 30631972 kB
Buffers 0 kB
Cached 16146588 kB
SwapCached 2028 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-09-24 22:38:28
DTS_END                         2022-09-24 22:38:28.5270
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/47484
TRN_ID                          233829
ATT_ID                          626
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: 9389.15 26172 1126698
customer order: creation 162.69 357 19523
customer order: refuse 13.69 329 1643
order to supplier: creation 43.88 1344 5266
order to supplier: removal 3.58 110400 430
invoice (draft): creation 43.33 3361 5199
invoice (draft): removal 54.56 894 6547
invoice accept: apply 40.58 17391 4869
invoice accept: cancel 50.01 794 6001
customer reserve: creation 3793.34 169 455201
customer reserve: removal 4894.38 63 587326
realization accept: apply 78.82 184 9458
realization accept: cancel 86.33 110 10360
payment from customer: creation 56.54 122 6785
payment from customer: removal 5.15 101 618
payment to supplier: creation 46.98 144 5638
payment to supplier: removal 3.43 73 411
service: total inventory turnovers 4.57 1246 548
service: total monetary turnovers 6.37 68 764
service: aggregate perf. data 0.89 20298 107
service: refresh index statistics 0.03 366000 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-09-2022 22:40:03 to 24-09-2022 22:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7806
TEST_TIME 2 9303
TEST_TIME 3 6489
TEST_TIME 4 5485
TEST_TIME 5 5205
TEST_TIME 6 5234
TEST_TIME 7 5092
TEST_TIME 8 5548
TEST_TIME 9 5947
TEST_TIME 10 5910
TEST_TIME 11 5790
TEST_TIME 12 5814
TEST_TIME 13 5861
TEST_TIME 14 6075
TEST_TIME 15 6505
TEST_TIME 16 6451
TEST_TIME 17 7050
TEST_TIME 18 7340
TEST_TIME 19 7298
TEST_TIME 20 7348
TEST_TIME 21 7997
TEST_TIME 22 8250
TEST_TIME 23 8626
TEST_TIME 24 8889
TEST_TIME 25 8684
TEST_TIME 26 9269
TEST_TIME 27 9436
TEST_TIME 28 9623
TEST_TIME 29 11619
TEST_TIME 30 11303
TEST_TIME 31 11131
TEST_TIME 32 11034
TEST_TIME 33 10764
TEST_TIME 34 10743
TEST_TIME 35 10574
TEST_TIME 36 10560
TEST_TIME 37 10369
TEST_TIME 38 10214
TEST_TIME 39 10044
TEST_TIME 40 10320
TEST_TIME 41 10144
TEST_TIME 42 10062
TEST_TIME 43 9957
TEST_TIME 44 9970
TEST_TIME 45 9889
TEST_TIME 46 9784
TEST_TIME 47 9698
TEST_TIME 48 9582
TEST_TIME 49 9556
TEST_TIME 50 9578
TEST_TIME 51 9455
TEST_TIME 52 9411
TEST_TIME 53 9383
TEST_TIME 54 9350
TEST_TIME 55 9290
TEST_TIME 56 9255
TEST_TIME 57 9360
TEST_TIME 58 9384
TEST_TIME 59 9328
TEST_TIME 60 9555
TEST_TIME 61 9648
TEST_TIME 62 9598
TEST_TIME 63 9721
TEST_TIME 64 9782
TEST_TIME 65 9882
TEST_TIME 66 9823
TEST_TIME 67 9747
TEST_TIME 68 9673
TEST_TIME 69 9645
TEST_TIME 70 9611
TEST_TIME 71 9604
TEST_TIME 72 9544
TEST_TIME 73 9529
TEST_TIME 74 9514
TEST_TIME 75 9436
TEST_TIME 76 9372
TEST_TIME 77 9341
TEST_TIME 78 9322
TEST_TIME 79 9313
TEST_TIME 80 9300
TEST_TIME 81 9335
TEST_TIME 82 9371
TEST_TIME 83 9425
TEST_TIME 84 9445
TEST_TIME 85 9424
TEST_TIME 86 9459
TEST_TIME 87 9444
TEST_TIME 88 9503
TEST_TIME 89 9593
TEST_TIME 90 9582
TEST_TIME 91 9617
TEST_TIME 92 9540
TEST_TIME 93 9493
TEST_TIME 94 9479
TEST_TIME 95 9452
TEST_TIME 96 9429
TEST_TIME 97 9496
TEST_TIME 98 9475
TEST_TIME 99 9452
TEST_TIME 100 9447
TEST_TIME 101 9440
TEST_TIME 102 9473
TEST_TIME 103 9487
TEST_TIME 104 9562
TEST_TIME 105 9519
TEST_TIME 106 9594
TEST_TIME 107 9593
TEST_TIME 108 9564
TEST_TIME 109 9540
TEST_TIME 110 9480
TEST_TIME 111 9453
TEST_TIME 112 9459
TEST_TIME 113 9489
TEST_TIME 114 9458
TEST_TIME 115 9421
TEST_TIME 116 9410
TEST_TIME 117 9360
TEST_TIME 118 9308
TEST_TIME 119 9333
TEST_TIME 120 9395

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2022 22:40:07 to 24-09-2022 22:40:10.

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 1547161 1547161 0 0.00 0 15804 6 0 0
doc_list_biud 1547159 1547159 0 0.00 0 50821 30 0 0
sp_add_invoice_to_stock 4993 4869 124 2.48 33 171362 17391 0 124
sp_cancel_adding_invoice 6001 6001 0 0.00 4 62479 795 0 0
sp_cancel_client_order 1643 1643 0 0.00 5 18509 329 0 0
sp_cancel_customer_reserve 587326 587326 0 0.00 0 50823 63 0 0
sp_cancel_pay_from_customer 618 618 0 0.00 3 10721 101 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 3200 73 0 0
sp_cancel_supplier_invoice 6559 6547 12 0.18 1 20024 894 0 12
sp_cancel_supplier_order 430 430 0 0.00 8 1285060 110400 0 0
sp_cancel_write_off 10360 10360 0 0.00 1 13447 111 0 0
sp_client_order 19523 19523 0 0.00 3 27713 357 0 0
sp_customer_reserve 455201 455201 0 0.00 0 167075 170 0 0
sp_fill_shopping_cart 29985 29985 0 0.00 1 30989 39 0 0
sp_fill_shopping_cart_clo_res 455189 455189 0 0.00 0 16026 15 0 0
sp_get_clo_for_invoice 4855 4855 0 0.00 0 45337 375 0 0
sp_kill_cost_storno 17390 17390 0 0.00 0 10714 55 0 0
sp_kill_qstorno_ret_qs2qd 1191989 1191989 0 0.00 0 50818 34 0 0
sp_kill_qty_storno 626620 626620 0 0.00 0 50821 73 0 0
sp_lock_selected_doc 19767 19767 0 0.00 0 7334 6 0 0
sp_make_cost_storno 26736 26736 0 0.00 0 15798 58 0 0
sp_multiply_rows_for_pdistr 26736 26736 0 0.00 0 11415 28 0 0
sp_multiply_rows_for_qdistr 19523 19523 0 0.00 0 16103 160 0 0
sp_pay_from_customer 6785 6785 0 0.00 4 15079 122 0 0
sp_pay_to_supplier 5638 5638 0 0.00 4 18491 144 0 0
sp_payment_common 12423 12423 0 0.00 2 15025 36 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 18497 298 0 0
sp_qd_handle_on_invoice_upd_sts 10856 10856 0 0.00 0 21236 320 0 0
sp_qd_handle_on_reserve_upd_sts 9458 9458 0 0.00 0 19688 59 0 0
sp_reserve_write_off 9458 9458 0 0.00 6 19735 185 0 0
sp_supplier_invoice 5199 5199 0 0.00 6 111984 3362 0 0
sp_supplier_order 5266 5266 0 0.00 5 41034 1345 0 0
srv_aggregate_perf_data 107 107 0 0.00 2724 499763 20298 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1314 157848 45556 0 0
srv_find_qd_qs_mism 1080169 1080169 0 0.00 0 15535 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 40 60687 1246 0 0
srv_make_money_saldo 764 764 0 0.00 0 32510 68 0 0
srv_recalc_idx_stat 4 4 0 0.00 93496 622999 366001 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 0 14132 169 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 7246 132 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 4206 15 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 10008 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 618 618 0 0.00 0 4945 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1010 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 425 12 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 27896 1609 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 5524 13 0 0
t$perf-norm:sp_client_order 19523 19523 0 0.00 0 7407 16 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 4968 11 0 0
t$perf-norm:sp_pay_from_customer 6785 6785 0 0.00 0 6677 15 0 0
t$perf-norm:sp_pay_to_supplier 5638 5638 0 0.00 0 7882 13 0 0
t$perf-norm:sp_reserve_write_off 9458 9458 0 0.00 0 10607 12 0 0
t$perf-norm:sp_supplier_invoice 5199 5199 0 0.00 0 3994 18 0 0
t$perf-norm:sp_supplier_order 5266 5266 0 0.00 0 5641 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 594 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 4513 23 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 869 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 9958 9710 248 2.49 0 7282 20 0 248
v_all_customers 35964 35964 0 0.00 0 161 1 0 0
v_all_suppliers 29292 29292 0 0.00 0 105 1 0 0
v_all_wares 39046 39046 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 999 7 0 0
v_cancel_client_order 3286 3286 0 0.00 0 3019 5 0 0
v_cancel_customer_prepayment 1236 1236 0 0.00 0 1898 6 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 282 3 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 3095 10 0 0
v_cancel_supplier_invoice 774 750 24 3.10 0 1092 16 0 24
v_cancel_supplier_order 860 860 0 0.00 0 567 5 0 0
v_cancel_write_off 1906 1906 0 0.00 0 5394 17 0 0
v_max_id_clo_ord 5266 5266 0 0.00 0 270 1 0 0
v_max_id_clo_res 936 936 0 0.00 0 3225 15 0 0
v_max_id_ord_sup 5196 5196 0 0.00 0 3874 3 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 3075 7 0 0
v_max_non_paid_realizn 3375 3375 0 0.00 0 967 4 0 0
v_min_id_clo_ord 5266 5266 0 0.00 0 3875 5 0 0
v_min_id_clo_res 936 936 0 0.00 0 4238 15 0 0
v_min_id_ord_sup 5196 5196 0 0.00 0 5591 8 0 0
v_min_non_paid_invoice 5638 5638 0 0.00 0 11206 30 0 0
v_min_non_paid_realizn 6785 6785 0 0.00 0 15011 28 0 0
v_our_firm 9902 9902 0 0.00 0 20 1 0 0
v_reserve_write_off 18916 18916 0 0.00 0 12024 7 0 0
x_lock_depdocs_on_canc_invoice 12173 12173 0 0.00 0 34950 134 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 6955 409 0 0
x_make_qstorno_1000_1200 5266 5266 0 0.00 2 40900 1271 0 0
x_make_qstorno_1200_2000 5199 5199 0 0.00 3 102569 3218 0 0
x_make_qstorno_2100_3300 455200 455200 0 0.00 0 167075 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-09-2022 22:40:11 to 24-09-2022 22:40:16.

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:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:33 19183 8192000000 9892349584 10199011328 971914800 10.605 100 88 176 0 1001260064 5728000 23617264
20:10:57 16134 8192000000 9933803312 10253639680 1014979168 11.024 100 87 174 0 1044922112 7626480 22316464
20:12:21 11435 8192000000 9967140048 10299842560 1054092848 11.400 100 81 160 0 1083550688 8856800 20601040
20:13:39 17526 8192000000 10033102336 10378289152 1111637728 11.948 100 84 167 0 1142026096 10412160 19976208
20:15:02 10857 8192000000 10076236560 10435944448 1159629888 12.400 100 77 154 0 1193090976 15874864 17586224
20:16:18 4596 8192000000 10118288864 10484056064 1205382032 12.826 100 80 157 0 1235259840 12674464 17203344
20:17:24 1794 8192000000 10166149040 10535174144 1243657392 13.180 100 91 183 0 1278978912 15210272 20111248
20:18:28 5412 8192000000 10171202512 10553602048 1264543056 13.372 100 67 134 1 1290775120 11775104 14456960
20:19:35 1047 8192000000 10211763344 10599526400 1297122672 13.669 100 82 165 0 1329002096 13930192 17949232
20:20:39 26078 8192000000 10191461648 10603782144 1306011952 13.750 100 39 79 0 1322206224 7313536 8880736
20:22:16 7122 8192000000 10193711888 10611273728 1311014976 13.795 100 16 31 0 1319944016 5808816 3120224
20:23:24 5862 8192000000 10200916192 10618740736 1316741200 13.847 100 24 50 0 1328515104 5844256 5929648
20:24:31 7373 8192000000 10201753152 10626457600 1324771184 13.920 100 10 20 1 1332469728 5606768 2091776
20:25:40 46686 8192000000 10216846464 10642673664 1330787840 13.974 100 23 46 0 1341697040 5393568 5515632
20:27:32 5816 8192000000 10222396768 10640736256 1326286640 13.934 100 33 67 0 1341506256 7922800 7296816
20:28:39 7102 8192000000 10204258800 10621554688 1294295424 13.643 98 21 44 0 1304614496 5407248 4911824
20:29:48 8948 8192000000 10172969584 10573393920 1260835968 13.338 98 23 45 1 1271973472 5342016 5795488
20:30:58 46489 8192000000 10119987200 10512408576 1144088944 12.254 90 27 54 0 1159603888 9423632 6091312
20:32:45 6769 8192000000 10129614000 10528595968 1221530448 12.976 98 19 38 1 1231430320 5685856 4214016
20:33:53 8455 8192000000 10163789168 10571128832 1268468304 13.408 100 22 42 0 1278691536 4935696 5287536
20:35:02 11085 8192000000 10190376480 10602336256 1297971120 13.677 100 22 44 0 1308743648 5407200 5365328
20:36:16 31190 8192000000 10191686352 10609414144 1303030448 13.723 100 27 54 0 1314914528 5295920 6588160
20:37:47 4586 8192000000 10206014960 10619711488 1311069696 13.796 100 23 46 0 1321856208 5691776 5094736
20:38:55 13730 8192000000 10249854896 10655907840 1343497520 14.089 100 58 115 0 1365740400 9282480 12960400
20:40:32 86395 8192000000 10262928816 10690818048 1362431664 14.259 100 30 61 0 1377947728 8148048 7368016
20:43:01 20302 8192000000 10306444112 10728112128 1387955280 14.488 100 53 105 0 1421146976 21469744 11721952
20:44:24 31859 8192000000 10309711648 10735640576 1385229600 14.463 99 39 78 0 1412473744 18125744 9118400
20:46:02 35186 8192000000 10327174112 10761551872 1411302128 14.696 100 51 99 0 1447641440 23805152 12534160
20:47:44 8555 8192000000 10341838640 10766036992 1408947248 14.675 99 48 94 0 1446586400 25693232 11945920
20:48:56 4993 8192000000 10346699968 10779869184 1425858528 14.825 100 45 83 0 1462482400 25367856 11256016
20:50:08 9596 8192000000 10359542416 10790674432 1432450896 14.883 100 45 85 0 1470122912 27007408 10664608
20:51:21 6395 8192000000 10359148672 10784002048 1425681584 14.823 100 59 117 0 1469981056 30165936 14133536
20:52:33 2123 8192000000 10372932848 10787459072 1424909008 14.816 100 81 162 0 1479059936 34724976 19425952
20:53:38 3804 8192000000 10380150992 10796318720 1422965632 14.799 100 73 142 0 1479574000 39360368 17248000
20:54:49 2369 8192000000 10380715584 10799476736 1416243024 14.739 99 69 136 3 1481779248 47867536 17668688
20:55:57 1313 8192000000 10361074384 10764308480 1391937056 14.523 99 86 172 0 1462381136 49430704 21013376
20:57:04 11720 8192000000 10359823360 10777743360 1389843712 14.504 99 60 118 1 1455939040 51155200 14940128
20:58:21 3183 8192000000 10357891696 10759651328 1367342048 14.303 99 80 160 0 1453220032 64910128 20967856
20:59:29 5496 8192000000 10371030576 10789326848 1400182960 14.597 100 49 97 0 1475833312 62002928 13647424
21:00:43 4847 8192000000 10363634096 10782224384 1398834208 14.585 100 48 93 1 1466691200 55316720 12540272
21:01:52 5063 8192000000 10340879712 10765283328 1371520432 14.341 99 52 104 1 1446644672 61103888 14020352
21:03:02 9969 8192000000 10355398336 10779279360 1389711168 14.503 100 30 60 1 1454285808 55732160 8842480
21:04:13 22065 8192000000 10338527024 10783105024 1381171840 14.427 100 30 60 1 1462523376 72145920 9205616
21:06:10 26421 8192000000 10343890736 10778247168 1351949072 14.165 99 34 67 1 1432000944 70324400 9727472
21:07:44 43656 8192000000 10281375216 10716762112 1347253200 14.123 98 22 44 1 1369421728 16607680 5560848
21:09:52 20168 8192000000 10282693472 10737430528 1376238064 14.383 100 18 35 0 1394881472 14775744 3867664
21:11:15 157848 8192000000 10282051024 10732683264 1341624576 14.072 97 13 26 0 1359036624 15266160 2145888
21:15:01 30898 8192000000 10296916352 10748887040 1385817168 14.469 100 17 34 0 1400437008 10607072 4012768
21:16:33 112496 8192000000 10278626912 10747150336 1361192480 14.248 99 24 49 0 1385958416 18393440 6372496
21:19:31 41468 8192000000 10255203840 10704519168 1340205024 14.059 98 8 16 1 1354384304 12470960 1708320
21:21:13 74093 8192000000 10269204400 10726256640 1373298576 14.357 100 28 56 0 1389374096 10136480 5939040
21:23:29 34192 8192000000 10271197248 10722103296 1350131536 14.149 98 42 84 0 1368951664 8447568 10372560
21:25:07 118523 8192000000 10284396272 10739929088 1366717824 14.298 99 14 28 0 1382500608 12496832 3285952
21:28:08 73411 8192000000 10269713184 10733355008 1368729424 14.316 99 5 10 0 1380430704 10628960 1072320
21:30:23 46627 8192000000 10271093440 10737295360 1356577632 14.207 98 10 20 0 1367858816 9373360 1907824
21:32:09 59450 8192000000 10288576960 10746499072 1364577776 14.278 98 19 38 0 1375599520 7109040 3912704
21:34:13 10062 8192000000 10324282400 10770096128 1402667616 14.619 100 53 105 1 1427796016 13576784 11551616
21:35:24 34954 8192000000 10308078992 10775977984 1401612000 14.609 100 39 78 0 1425280048 14900672 8767376
21:36:59 34307 8192000000 10334623888 10795118592 1403463536 14.626 99 24 48 0 1431883040 23030336 5389168
21:38:47 56091 8192000000 10316960000 10793177088 1366069968 14.292 97 9 19 0 1390949424 22907328 1972128
21:40:44 46550 8192000000 10321109776 10780160000 1402063664 14.613 100 33 66 0 1427575232 18291536 7220032
21:43:12 74320 8192000000 10288890912 10744508416 1302929344 13.722 93 12 25 0 1315801920 10022304 2850272
21:45:28 93460 8192000000 10279842480 10725085184 1354392880 14.187 98 46 91 1 1376505904 11624448 10488576
21:48:04 37415 8192000000 10267381696 10728243200 1340322128 14.060 98 10 20 0 1356369424 13755248 2292048
21:49:42 49300 8192000000 10257539552 10719977472 1341326592 14.069 98 11 20 0 1357522080 13624480 2571008
21:52:17 44557 8192000000 10258264144 10720923648 1345902656 14.111 98 10 20 0 1356906960 8442224 2562080
21:54:11 88417 8192000000 10272740400 10730024960 1346800352 14.119 98 25 51 1 1364430064 12101200 5528512
21:56:46 32539 8192000000 10274577152 10732404736 1350200752 14.149 97 26 52 0 1363619856 7844064 5575040
21:58:22 41551 8192000000 10265620560 10720960512 1337894496 14.038 98 29 58 1 1364123120 19362976 6865648
22:00:08 39688 8192000000 10277729872 10730401792 1366477200 14.295 100 15 30 0 1387759744 17767136 3515408
22:01:53 59923 8192000000 10272206016 10720804864 1329798576 13.965 97 15 30 0 1347444224 14443792 3201856
22:04:03 32595 8192000000 10268562144 10721787904 1352656944 14.171 99 34 68 0 1373340592 13197040 7486608
22:05:40 36999 8192000000 10269830880 10730057728 1365921536 14.290 100 25 50 0 1382534144 11373952 5238656
22:07:19 70973 8192000000 10311348864 10779836416 1377138592 14.391 100 8 16 0 1408697808 29819152 1740064
22:09:30 85628 8192000000 10283821152 10746957824 1380839824 14.424 100 23 46 1 1403399568 17521344 5038400
22:11:56 39815 8192000000 10272242976 10732249088 1380309328 14.419 100 19 38 0 1392791184 8381088 4100768
22:13:37 55541 8192000000 10278012944 10742530048 1375167600 14.373 99 11 21 0 1388047216 10640304 2239312
22:15:34 89291 8192000000 10278072528 10728460288 1307362032 13.762 94 15 30 0 1321837776 11155664 3320080
22:18:04 64168 8192000000 10291041632 10736500736 1362932272 14.264 98 23 46 0 1379307792 11155280 5220240
22:20:09 53178 8192000000 10283167152 10749419520 1379083680 14.408 100 21 43 0 1394822320 11313056 4425584
22:22:06 87836 8192000000 10298453344 10744737792 1362602624 14.261 98 14 29 0 1384362160 17858240 3901296
22:24:34 98759 8192000000 10295834336 10756120576 1334069328 14.004 96 12 24 0 1352398512 15695072 2634112
22:27:14 41535 8192000000 10282682496 10727989248 1368282208 14.312 99 36 72 1 1385685104 9245056 8157840
22:28:56 87758 8192000000 10273909520 10731663360 1331105168 13.977 96 22 44 0 1348639872 12372272 5162432
22:31:28 80976 8192000000 10254682128 10720387072 1345573776 14.108 98 9 17 0 1356830272 9475360 1781136
22:33:50 81982 8192000000 10275383984 10725785600 1313061776 13.814 95 19 38 1 1336221488 17910416 5249296
22:36:12 57025 8192000000 10276540592 10730536960 1355280800 14.195 99 18 36 0 1379547584 20090320 4176464
22:38:16 25702 8192000000 9102155760 9353420800 262782640 3.108 20 2 4 0 272709088 9841376 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-09-2022 22:40:18 to 24-09-2022 22:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2022 22:40:34 to 24-09-2022 22:40:36.

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          233467
MON$OLDEST_ACTIVE               234101
MON$OLDEST_SNAPSHOT             234101
MON$NEXT_TRANSACTION            234101
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                       2033120
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.33629 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33629 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33629 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33629 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Sep 24 22:38:45 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Sep 24 22:38:45 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
--- end of diff output ---

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

24.09.22 22:40:37. 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-09-24 22:40:39 No severe PSQL-related problems occured
24.09.22 22:40:41. End of report.