Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.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 14781820 kB
MemAvailable 30068212 kB
Buffers 0 kB
Cached 16902948 kB
SwapCached 3196 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-12-13 22:38:31
DTS_END                         2022-12-13 22:38:31.8840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40038
TRN_ID                          244955
ATT_ID                          702
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: 9658.13 15018 1158975
customer order: creation 183.43 360 22011
customer order: refuse 14.90 371 1788
order to supplier: creation 47.72 1320 5726
order to supplier: removal 3.70 88430 444
invoice (draft): creation 47.27 3126 5672
invoice (draft): removal 56.03 762 6724
invoice accept: apply 43.44 17675 5213
invoice accept: cancel 50.79 712 6095
customer reserve: creation 3974.11 177 476893
customer reserve: removal 4926.11 52 591133
realization accept: apply 87.02 176 10442
realization accept: cancel 87.75 101 10530
payment from customer: creation 61.26 113 7351
payment from customer: removal 6.43 89 771
payment to supplier: creation 51.24 131 6149
payment to supplier: removal 3.65 88 438
service: total inventory turnovers 5.41 971 649
service: total monetary turnovers 6.92 35 830
service: aggregate perf. data 0.93 17235 112
service: refresh index statistics 0.03 168436 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2022 22:39:58 to 13-12-2022 22:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8431
TEST_TIME 2 4899
TEST_TIME 3 5097
TEST_TIME 4 5846
TEST_TIME 5 5536
TEST_TIME 6 5545
TEST_TIME 7 5868
TEST_TIME 8 5932
TEST_TIME 9 5899
TEST_TIME 10 6614
TEST_TIME 11 6673
TEST_TIME 12 6454
TEST_TIME 13 6519
TEST_TIME 14 7067
TEST_TIME 15 6969
TEST_TIME 16 7259
TEST_TIME 17 7483
TEST_TIME 18 7603
TEST_TIME 19 7770
TEST_TIME 20 8023
TEST_TIME 21 8089
TEST_TIME 22 8285
TEST_TIME 23 8722
TEST_TIME 24 8741
TEST_TIME 25 9202
TEST_TIME 26 9186
TEST_TIME 27 9395
TEST_TIME 28 9583
TEST_TIME 29 9831
TEST_TIME 30 10077
TEST_TIME 31 10439
TEST_TIME 32 10638
TEST_TIME 33 10542
TEST_TIME 34 10658
TEST_TIME 35 10587
TEST_TIME 36 10513
TEST_TIME 37 10395
TEST_TIME 38 10172
TEST_TIME 39 10222
TEST_TIME 40 10134
TEST_TIME 41 10296
TEST_TIME 42 10095
TEST_TIME 43 10004
TEST_TIME 44 9977
TEST_TIME 45 9888
TEST_TIME 46 9821
TEST_TIME 47 9797
TEST_TIME 48 9966
TEST_TIME 49 10193
TEST_TIME 50 10039
TEST_TIME 51 9937
TEST_TIME 52 10130
TEST_TIME 53 10046
TEST_TIME 54 10052
TEST_TIME 55 10008
TEST_TIME 56 9992
TEST_TIME 57 10003
TEST_TIME 58 10062
TEST_TIME 59 10054
TEST_TIME 60 10027
TEST_TIME 61 10078
TEST_TIME 62 10139
TEST_TIME 63 10134
TEST_TIME 64 10160
TEST_TIME 65 10218
TEST_TIME 66 10246
TEST_TIME 67 10252
TEST_TIME 68 10150
TEST_TIME 69 10045
TEST_TIME 70 10114
TEST_TIME 71 10010
TEST_TIME 72 10000
TEST_TIME 73 10043
TEST_TIME 74 10020
TEST_TIME 75 9944
TEST_TIME 76 9911
TEST_TIME 77 9891
TEST_TIME 78 9899
TEST_TIME 79 9881
TEST_TIME 80 9854
TEST_TIME 81 9832
TEST_TIME 82 9793
TEST_TIME 83 9838
TEST_TIME 84 9868
TEST_TIME 85 9830
TEST_TIME 86 9900
TEST_TIME 87 9858
TEST_TIME 88 9871
TEST_TIME 89 9852
TEST_TIME 90 9904
TEST_TIME 91 9881
TEST_TIME 92 9899
TEST_TIME 93 9940
TEST_TIME 94 9921
TEST_TIME 95 9856
TEST_TIME 96 9821
TEST_TIME 97 9776
TEST_TIME 98 9750
TEST_TIME 99 9720
TEST_TIME 100 9699
TEST_TIME 101 9683
TEST_TIME 102 9645
TEST_TIME 103 9584
TEST_TIME 104 9568
TEST_TIME 105 9558
TEST_TIME 106 9607
TEST_TIME 107 9589
TEST_TIME 108 9614
TEST_TIME 109 9585
TEST_TIME 110 9659
TEST_TIME 111 9699
TEST_TIME 112 9664
TEST_TIME 113 9648
TEST_TIME 114 9718
TEST_TIME 115 9693
TEST_TIME 116 9673
TEST_TIME 117 9734
TEST_TIME 118 9767
TEST_TIME 119 9737
TEST_TIME 120 9658

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-12-2022 22:40:02 to 13-12-2022 22: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 1601495 1601495 0 0.00 0 28630 6 0 0
doc_list_biud 1601494 1601494 0 0.00 0 63093 25 0 0
sp_add_invoice_to_stock 5325 5213 112 2.10 22 176610 17675 0 112
sp_cancel_adding_invoice 6095 6095 0 0.00 4 66491 712 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 12110 371 0 0
sp_cancel_customer_reserve 591133 591133 0 0.00 1 63096 52 0 0
sp_cancel_pay_from_customer 771 771 0 0.00 3 8651 90 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 5144 89 0 0
sp_cancel_supplier_invoice 6740 6724 16 0.23 2 16260 762 0 16
sp_cancel_supplier_order 444 444 0 0.00 12 1066014 88431 0 0
sp_cancel_write_off 10530 10530 0 0.00 1 8942 102 0 0
sp_client_order 22011 22011 0 0.00 3 25974 360 0 0
sp_customer_reserve 476893 476893 0 0.00 0 44257 178 0 0
sp_fill_shopping_cart 33405 33405 0 0.00 1 12985 34 0 0
sp_fill_shopping_cart_clo_res 476891 476891 0 0.00 0 12780 12 0 0
sp_get_clo_for_invoice 5211 5211 0 0.00 0 22225 247 0 0
sp_kill_cost_storno 17834 17834 0 0.00 0 28625 51 0 0
sp_kill_qstorno_ret_qs2qd 1199964 1199964 0 0.00 0 63091 29 0 0
sp_kill_qty_storno 632366 632366 0 0.00 0 63092 62 0 0
sp_lock_selected_doc 21647 21647 0 0.00 0 6662 5 0 0
sp_make_cost_storno 29152 29152 0 0.00 0 25329 56 0 0
sp_multiply_rows_for_pdistr 29152 29152 0 0.00 0 7437 27 0 0
sp_multiply_rows_for_qdistr 22011 22011 0 0.00 0 13237 165 0 0
sp_pay_from_customer 7351 7351 0 0.00 4 12796 114 0 0
sp_pay_to_supplier 6149 6149 0 0.00 4 15664 132 0 0
sp_payment_common 13500 13500 0 0.00 2 12788 33 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 12102 338 0 0
sp_qd_handle_on_invoice_upd_sts 11305 11305 0 0.00 0 11205 292 0 0
sp_qd_handle_on_reserve_upd_sts 10442 10442 0 0.00 0 17145 57 0 0
sp_reserve_write_off 10442 10442 0 0.00 5 17197 177 0 0
sp_supplier_invoice 5672 5672 0 0.00 5 79752 3126 0 0
sp_supplier_order 5726 5726 0 0.00 5 31982 1320 0 0
srv_aggregate_perf_data 112 112 0 0.00 2627 82369 17235 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2247 152753 38774 0 0
srv_find_qd_qs_mism 1109579 1109579 0 0.00 0 15956 5 0 0
srv_make_invnt_saldo 649 649 0 0.00 60 28283 971 0 0
srv_make_money_saldo 830 830 0 0.00 1 3423 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 112169 226974 168437 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5213 5213 0 0.00 0 17654 187 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 5690 142 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 8638 15 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 2352 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 771 771 0 0.00 0 12962 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 479 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 3432 26 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 35772 1469 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 1704 8 0 0
t$perf-norm:sp_client_order 22011 22011 0 0.00 0 13482 17 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 325 3 0 0
t$perf-norm:sp_pay_from_customer 7351 7351 0 0.00 0 6084 10 0 0
t$perf-norm:sp_pay_to_supplier 6149 6149 0 0.00 0 10733 12 0 0
t$perf-norm:sp_reserve_write_off 10442 10442 0 0.00 0 4361 9 0 0
t$perf-norm:sp_supplier_invoice 5672 5672 0 0.00 0 8371 19 0 0
t$perf-norm:sp_supplier_order 5726 5726 0 0.00 0 7762 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1892 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 75 5 0 0
t$perf-norm:srv_make_invnt_saldo 649 649 0 0.00 0 1543 22 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 1687 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10642 10418 224 2.10 0 11425 23 0 224
v_all_customers 39374 39374 0 0.00 0 61 1 0 0
v_all_suppliers 31652 31652 0 0.00 0 19 1 0 0
v_all_wares 44022 44022 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1964 9 0 0
v_cancel_client_order 3576 3576 0 0.00 0 11930 10 0 0
v_cancel_customer_prepayment 1542 1542 0 0.00 0 8634 12 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 2224 6 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 5085 15 0 0
v_cancel_supplier_invoice 830 798 32 3.85 0 1843 18 0 32
v_cancel_supplier_order 888 888 0 0.00 0 1132 5 0 0
v_cancel_write_off 2014 2014 0 0.00 0 2421 16 0 0
v_max_id_clo_ord 5726 5726 0 0.00 0 1627 2 0 0
v_max_id_clo_res 979 979 0 0.00 0 5007 24 0 0
v_max_id_ord_sup 5668 5668 0 0.00 0 4650 3 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 503 3 0 0
v_max_non_paid_realizn 4043 4043 0 0.00 0 2948 6 0 0
v_min_id_clo_ord 5726 5726 0 0.00 0 3386 8 0 0
v_min_id_clo_res 979 979 0 0.00 0 5057 9 0 0
v_min_id_ord_sup 5668 5668 0 0.00 0 3928 6 0 0
v_min_non_paid_invoice 6149 6149 0 0.00 0 8397 26 0 0
v_min_non_paid_realizn 7351 7351 0 0.00 0 8000 25 0 0
v_our_firm 11264 11264 0 0.00 0 18 1 0 0
v_reserve_write_off 20880 20880 0 0.00 0 5358 8 0 0
x_lock_depdocs_on_canc_invoice 12420 12420 0 0.00 0 13000 106 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 8978 318 0 0
x_make_qstorno_1000_1200 5726 5726 0 0.00 1 31972 1236 0 0
x_make_qstorno_1200_2000 5672 5672 0 0.00 2 76611 3009 0 0
x_make_qstorno_2100_3300 476893 476893 0 0.00 0 44256 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2022 22:40:06 to 13-12-2022 22: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
20:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 21013 8192000000 9889770528 10196807680 970234752 10.589 100 85 170 0 998452144 5479664 22737728
20:11:04 19149 8192000000 9948555024 10270953472 1033418608 11.201 100 89 178 0 1063930432 7863456 22648368
20:12:29 11412 8192000000 9984900000 10318475264 1070908368 11.561 100 85 170 0 1101270224 9513088 20848768
20:13:47 9885 8192000000 10018843024 10365222912 1109986304 11.932 100 78 156 0 1139614608 11422416 18205888
20:15:06 5262 8192000000 10066667360 10424999936 1157422992 12.379 100 80 160 0 1189352528 13069936 18859600
20:16:15 3287 8192000000 10113952224 10478301184 1197133392 12.750 100 76 152 0 1227146560 12228560 17784608
20:17:22 3797 8192000000 10150118848 10521763840 1237659424 13.125 100 82 159 1 1267021984 11574880 17787680
20:18:28 1330 8192000000 10186026768 10561572864 1271984320 13.440 100 88 174 0 1306224752 14493136 19747296
20:19:31 1682 8192000000 10194513152 10581241856 1284448000 13.554 100 79 155 0 1313809424 12315088 17046336
20:20:35 26146 8192000000 10196932896 10591477760 1295495888 13.654 100 50 101 0 1313018560 5647760 11874912
20:22:04 7773 8192000000 10192660736 10605830144 1304916752 13.740 100 30 59 1 1318038816 6728336 6393728
20:23:16 8251 8192000000 10196120720 10610737152 1311445296 13.799 100 17 34 1 1321065312 5829024 3790992
20:24:27 13775 8192000000 10209465136 10628796416 1322352064 13.898 100 19 39 1 1340754352 14457696 3944592
20:25:44 11689 8192000000 10227273168 10636668928 1326077488 13.932 100 57 113 1 1345941936 5815040 14049408
20:26:57 4572 8192000000 10216617008 10631979008 1323588544 13.909 100 26 50 0 1335786352 5926848 6270960
20:28:05 7854 8192000000 10209924928 10629136384 1321213536 13.888 99 22 46 0 1332287728 5791440 5282752
20:29:14 8200 8192000000 10189118576 10605170688 1292037568 13.623 99 22 45 0 1302226624 5437440 4751616
20:30:29 12550 8192000000 10170910208 10586689536 1243171136 13.175 95 19 38 0 1257591072 10264448 4155488
20:31:46 6993 8192000000 10144371904 10547376128 1221584304 12.976 96 31 62 0 1234152496 4816720 7751472
20:32:54 8612 8192000000 10136608336 10536431616 1220163040 12.963 98 16 32 0 1228987888 4757664 4067184
20:34:03 6317 8192000000 10148091824 10560364544 1246529632 13.206 98 23 46 0 1258238608 6593552 5115424
20:35:10 32283 8192000000 10172482944 10582245376 1282316832 13.534 100 24 48 0 1297537872 10436496 4784544
20:36:45 8573 8192000000 10178786096 10596130816 1293129392 13.633 100 17 32 1 1302143504 5545536 3468576
20:37:54 9987 8192000000 10200557664 10615283712 1306559968 13.755 100 15 29 1 1314928032 5463296 2904768
20:39:09 5918 8192000000 10243650816 10652606464 1337988896 14.039 100 49 88 1 1355783760 7911584 9883280
20:40:21 29031 8192000000 10251537808 10668883968 1341575024 14.072 100 39 75 2 1361197536 11057040 8565472
20:43:16 12219 8192000000 10286660688 10705637376 1374258576 14.365 100 55 110 1 1398780576 11101136 13420864
20:44:32 8728 8192000000 10294406240 10714816512 1380315136 14.419 100 51 101 0 1406843024 14885952 11641936
20:45:51 8323 8192000000 10303896256 10727133184 1387915088 14.487 100 42 84 0 1415088672 17009968 10163616
20:47:06 9395 8192000000 10308399504 10732699648 1387147024 14.480 100 54 109 0 1420666096 21251792 12267280
20:48:20 5658 8192000000 10320888704 10745257984 1395355760 14.554 100 52 99 1 1429360704 22473200 11531744
20:49:36 23324 8192000000 10325830608 10750853120 1400707424 14.601 100 48 96 1 1436040160 24023456 11309280
20:51:07 5995 8192000000 10323855248 10743459840 1381314288 14.428 99 64 128 1 1424735184 28436864 14984032
20:52:23 7470 8192000000 10312651312 10737311744 1372343360 14.348 99 65 127 1 1413727120 26044208 15339552
20:53:41 3965 8192000000 10315393696 10734080000 1380869840 14.424 100 61 122 1 1423737792 28324512 14543440
20:54:48 2247 8192000000 10311724176 10729254912 1376533728 14.386 100 62 121 1 1422454704 31822448 14098528
20:55:59 7123 8192000000 10320989888 10732101632 1379005792 14.408 100 68 135 1 1423113376 27777040 16330544
20:57:19 2935 8192000000 10343702032 10756214784 1398304768 14.580 100 77 154 1 1446895824 29219712 19371344
20:58:27 4673 8192000000 10288967520 10705838080 1342143152 14.077 98 52 103 3 1385219632 30551072 12525408
20:59:37 2687 8192000000 10336126752 10737197056 1378262304 14.401 100 84 167 0 1438198528 39908304 20027920
21:00:47 2252 8192000000 10340289200 10746966016 1375459984 14.376 100 74 147 1 1433530960 39460336 18610640
21:01:54 10537 8192000000 10336197792 10763501568 1386842976 14.478 100 45 90 1 1441526672 42725248 11958448
21:03:15 13425 8192000000 10303138944 10732982272 1360163920 14.239 99 27 54 0 1408630320 40977392 7489008
21:04:30 10148 8192000000 10309242288 10737647616 1370387520 14.331 100 31 62 0 1415196944 36391712 8417712
21:05:49 17046 8192000000 10300404112 10734682112 1363064032 14.265 100 22 44 1 1409653200 40289920 6299248
21:07:08 31354 8192000000 10295024944 10727571456 1352050176 14.166 99 22 44 0 1395645824 37587568 6008080
21:09:19 27890 8192000000 10295706896 10739929088 1360781792 14.244 100 11 22 0 1394026128 30324560 2919776
21:10:51 14372 8192000000 10277222672 10708078592 1353804048 14.182 99 41 78 1 1375739584 13103344 8832192
21:12:08 62443 8192000000 10295942352 10743824384 1376444496 14.385 100 6 12 0 1391079856 13045680 1589680
21:14:28 29617 8192000000 10284249664 10725384192 1372177776 14.347 99 23 46 0 1383549408 6411056 4960576
21:16:03 35321 8192000000 10296378720 10734702592 1372158000 14.346 99 51 97 1 1388557728 4830352 11569376
21:17:39 50602 8192000000 10321395440 10762665984 1395373344 14.554 100 32 65 0 1416785824 14757520 6654960
21:19:34 74892 8192000000 10299638608 10756755456 1361832352 14.254 97 16 32 0 1382167792 16505760 3829680
21:21:51 6991 8192000000 10342085568 10784677888 1417991440 14.755 100 45 86 1 1445324288 17533392 9799456
21:23:06 24802 8192000000 10345762720 10794344448 1418952064 14.763 100 23 43 0 1444333280 20162496 5218720
21:24:33 20443 8192000000 10346318144 10796986368 1417539120 14.751 100 46 92 1 1454197152 25881680 10776352
21:25:54 20286 8192000000 10334718400 10800087040 1407890544 14.665 100 20 36 1 1447775968 34420704 5464720
21:27:16 30984 8192000000 10316775568 10794541056 1410367696 14.687 100 13 26 0 1434778768 21415184 2995888
21:29:09 65244 8192000000 10312473024 10780934144 1404991616 14.639 100 22 45 0 1428296432 17890416 5414400
21:31:14 57649 8192000000 10311143904 10770927616 1382293648 14.437 98 21 42 0 1395761504 8754384 4713472
21:33:14 29342 8192000000 10312398080 10764210176 1392247888 14.526 99 25 50 0 1406221824 8791408 5182528
21:34:46 44633 8192000000 10316982176 10772566016 1403624800 14.627 100 33 66 0 1421218944 10243696 7350448
21:36:35 53724 8192000000 10303095872 10764271616 1357444272 14.214 97 9 18 0 1374075120 14529488 2101360
21:38:29 90275 8192000000 10288652112 10741985280 1306015200 13.750 93 22 44 0 1332805408 21832608 4957600
21:41:00 74962 8192000000 10260529120 10713636864 1297943136 13.677 95 19 38 1 1314976016 12730160 4302720
21:43:16 54927 8192000000 10286235488 10748514304 1342505504 14.080 98 25 50 1 1370987280 22925584 5556192
21:45:21 56375 8192000000 10297307504 10750369792 1360635520 14.243 99 17 34 0 1380901600 16707360 3558720
21:47:30 77936 8192000000 10272842384 10724794368 1308533504 13.773 95 33 66 0 1333203536 16896528 7773504
21:49:49 11584 8192000000 10252933424 10694254592 1331851056 13.984 97 31 56 2 1352773712 13877152 7045504
21:51:01 70874 8192000000 10263943744 10723901440 1348749088 14.136 99 17 34 0 1367035808 14488768 3797952
21:53:14 67428 8192000000 10282734304 10732441600 1360786800 14.244 99 21 42 0 1381763088 15833152 5143136
21:55:31 61121 8192000000 10269112192 10730377216 1355721456 14.199 99 23 46 0 1375642784 13952480 5968848
21:59:32 55958 8192000000 10280641424 10737520640 1349414224 14.142 98 16 34 0 1364466576 11339968 3712384
22:01:36 48805 8192000000 10272117856 10722738176 1350343328 14.151 98 23 46 0 1364147968 8969488 4835152
22:03:31 53041 8192000000 10282496704 10735218688 1336745216 14.028 97 28 56 1 1354311744 11243552 6322976
22:05:53 17849 8192000000 10299534384 10747019264 1384379616 14.456 100 31 62 0 1398333632 7428224 6525792
22:07:11 152752 8192000000 10299175536 10755190784 1350762304 14.154 97 9 18 0 1364830160 12536048 1531808
22:10:48 85204 8192000000 10305918768 10777059328 1365819728 14.290 98 11 22 0 1387900048 19515536 2564784
22:13:19 111775 8192000000 10281328192 10747961344 1355143072 14.194 98 16 32 0 1376206592 17412016 3651504
22:16:46 56932 8192000000 10273371456 10741415936 1368590608 14.314 99 14 28 1 1383209744 11674128 2945008
22:20:11 61613 8192000000 10290410912 10745425920 1356228528 14.203 97 18 37 0 1367811808 7057792 4525488
22:22:24 59331 8192000000 10299727904 10769518592 1394198096 14.543 100 16 32 0 1414432208 16350272 3883840
22:24:26 78949 8192000000 10301604048 10777702400 1393438960 14.537 100 13 26 0 1414191024 17848288 2903776
22:26:46 59710 8192000000 10300176096 10756313088 1354642832 14.189 97 21 41 0 1369820272 10754784 4422656
22:28:46 51370 8192000000 10303243760 10770833408 1368517360 14.314 98 18 36 0 1389754896 17117456 4120080
22:30:41 44460 8192000000 10285119472 10732662784 1331111296 13.977 96 23 46 0 1346611200 10096976 5402928
22:32:28 52803 8192000000 10248478048 10689245184 1257011104 13.303 92 20 40 0 1269112512 7992352 4109056
22:34:27 16520 8192000000 10279033616 10708578304 1320741008 13.883 97 44 86 2 1340231072 9574224 9915840
22:35:44 36534 8192000000 10261734704 10713214976 1319684672 13.874 97 20 40 0 1336531776 12592560 4254544
22:37:30 76231 8192000000 9589022448 9929682944 729881984 8.180 54 16 32 0 738547232 4900864 3764384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-12-2022 22:40:12 to 13-12-2022 22:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2022 22:40:29 to 13-12-2022 22:40:31.

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          245161
MON$OLDEST_ACTIVE               245505
MON$OLDEST_SNAPSHOT             245505
MON$NEXT_TRANSACTION            245505
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                       2058144
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Tue Dec 13 22:38:58 2022
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:267: 
:268: 
:269: mscs-test-161.budzdorov.ru    Tue Dec 13 22:38:58 2022
:270:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:271: 
:272: 
:273: mscs-test-161.budzdorov.ru    Tue Dec 13 22:38:58 2022
:274:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:275: 
:276: 
:277: mscs-test-161.budzdorov.ru    Tue Dec 13 22:38:58 2022
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:279: 
:280: 
:281: mscs-test-161.budzdorov.ru    Tue Dec 13 22:38:58 2022
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:283: 
--- end of diff output ---

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

13.12.22 22:40:32. There are 6 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-12-13 22:40:35 No severe PSQL-related problems occured
13.12.22 22:40:36. End of report.