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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-12 19:38:45
DTS_END                         2023-03-12 19:38:45.6790
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/45980
TRN_ID                          237274
ATT_ID                          644
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: 8925.66 23534 1071079
customer order: creation 171.28 365 20554
customer order: refuse 14.34 339 1721
order to supplier: creation 46.71 1241 5605
order to supplier: removal 3.48 110874 418
invoice (draft): creation 45.65 3577 5478
invoice (draft): removal 51.18 826 6142
invoice accept: apply 41.39 19831 4967
invoice accept: cancel 46.10 948 5532
customer reserve: creation 3807.77 198 456932
customer reserve: removal 4409.68 69 529161
realization accept: apply 83.13 179 9976
realization accept: cancel 76.55 121 9186
payment from customer: creation 58.73 103 7048
payment from customer: removal 5.53 89 664
payment to supplier: creation 48.62 121 5834
payment to supplier: removal 3.09 89 371
service: total inventory turnovers 4.95 1185 594
service: total monetary turnovers 6.62 69 794
service: aggregate perf. data 0.82 18572 98
service: refresh index statistics 0.03 311880 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2023 19:39:39 to 12-03-2023 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1399
TEST_TIME 2 2776
TEST_TIME 3 3116
TEST_TIME 4 3925
TEST_TIME 5 4351
TEST_TIME 6 4630
TEST_TIME 7 5195
TEST_TIME 8 5143
TEST_TIME 9 5245
TEST_TIME 10 6324
TEST_TIME 11 6681
TEST_TIME 12 6647
TEST_TIME 13 6906
TEST_TIME 14 6742
TEST_TIME 15 7083
TEST_TIME 16 7279
TEST_TIME 17 7429
TEST_TIME 18 7914
TEST_TIME 19 8005
TEST_TIME 20 7940
TEST_TIME 21 7890
TEST_TIME 22 8122
TEST_TIME 23 8206
TEST_TIME 24 8282
TEST_TIME 25 8310
TEST_TIME 26 8725
TEST_TIME 27 8814
TEST_TIME 28 9159
TEST_TIME 29 9518
TEST_TIME 30 9860
TEST_TIME 31 10305
TEST_TIME 32 10630
TEST_TIME 33 10522
TEST_TIME 34 10420
TEST_TIME 35 10199
TEST_TIME 36 10210
TEST_TIME 37 10068
TEST_TIME 38 10020
TEST_TIME 39 9906
TEST_TIME 40 9798
TEST_TIME 41 9649
TEST_TIME 42 9587
TEST_TIME 43 9536
TEST_TIME 44 9498
TEST_TIME 45 9355
TEST_TIME 46 9435
TEST_TIME 47 9340
TEST_TIME 48 9272
TEST_TIME 49 9314
TEST_TIME 50 9294
TEST_TIME 51 9166
TEST_TIME 52 9115
TEST_TIME 53 9049
TEST_TIME 54 8952
TEST_TIME 55 9026
TEST_TIME 56 9084
TEST_TIME 57 9049
TEST_TIME 58 8976
TEST_TIME 59 8916
TEST_TIME 60 8951
TEST_TIME 61 8861
TEST_TIME 62 8817
TEST_TIME 63 8818
TEST_TIME 64 8787
TEST_TIME 65 8827
TEST_TIME 66 8894
TEST_TIME 67 8865
TEST_TIME 68 8829
TEST_TIME 69 8885
TEST_TIME 70 8923
TEST_TIME 71 8945
TEST_TIME 72 8961
TEST_TIME 73 8980
TEST_TIME 74 8999
TEST_TIME 75 8970
TEST_TIME 76 9013
TEST_TIME 77 8998
TEST_TIME 78 8970
TEST_TIME 79 8936
TEST_TIME 80 8897
TEST_TIME 81 8901
TEST_TIME 82 8866
TEST_TIME 83 8803
TEST_TIME 84 8779
TEST_TIME 85 8774
TEST_TIME 86 8755
TEST_TIME 87 8726
TEST_TIME 88 8718
TEST_TIME 89 8709
TEST_TIME 90 8731
TEST_TIME 91 8732
TEST_TIME 92 8780
TEST_TIME 93 8838
TEST_TIME 94 8813
TEST_TIME 95 8805
TEST_TIME 96 8831
TEST_TIME 97 8825
TEST_TIME 98 8866
TEST_TIME 99 8900
TEST_TIME 100 8948
TEST_TIME 101 8938
TEST_TIME 102 8970
TEST_TIME 103 8931
TEST_TIME 104 8880
TEST_TIME 105 8841
TEST_TIME 106 8838
TEST_TIME 107 8803
TEST_TIME 108 8802
TEST_TIME 109 8795
TEST_TIME 110 8770
TEST_TIME 111 8791
TEST_TIME 112 8782
TEST_TIME 113 8781
TEST_TIME 114 8798
TEST_TIME 115 8810
TEST_TIME 116 8856
TEST_TIME 117 8914
TEST_TIME 118 8932
TEST_TIME 119 8916
TEST_TIME 120 8926

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-03-2023 19:39:42 to 12-03-2023 19:39:46.

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 1493944 1493944 0 0.00 0 21496 7 0 0
doc_list_biud 1493944 1493944 0 0.00 0 26288 31 0 0
sp_add_invoice_to_stock 5064 4967 97 1.91 35 176130 19831 0 97
sp_cancel_adding_invoice 5532 5532 0 0.00 4 72513 949 0 0
sp_cancel_client_order 1721 1721 0 0.00 4 13794 339 0 0
sp_cancel_customer_reserve 529161 529161 0 0.00 0 26289 70 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 4625 89 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 4780 90 0 0
sp_cancel_supplier_invoice 6158 6142 16 0.25 1 15012 826 0 16
sp_cancel_supplier_order 418 418 0 0.00 4 942409 110874 0 0
sp_cancel_write_off 9186 9186 0 0.00 1 8683 122 0 0
sp_client_order 20554 20554 0 0.00 3 24476 366 0 0
sp_customer_reserve 456932 456932 0 0.00 0 39332 198 0 0
sp_fill_shopping_cart 31629 31629 0 0.00 1 18027 45 0 0
sp_fill_shopping_cart_clo_res 456923 456923 0 0.00 0 19601 15 0 0
sp_get_clo_for_invoice 4958 4958 0 0.00 0 28016 352 0 0
sp_kill_cost_storno 15753 15753 0 0.00 0 11333 63 0 0
sp_kill_qstorno_ret_qs2qd 1074068 1074068 0 0.00 0 26274 37 0 0
sp_kill_qty_storno 567094 567094 0 0.00 0 26288 80 0 0
sp_lock_selected_doc 20654 20654 0 0.00 0 7208 5 0 0
sp_make_cost_storno 27816 27816 0 0.00 0 15310 51 0 0
sp_multiply_rows_for_pdistr 27816 27816 0 0.00 0 9637 24 0 0
sp_multiply_rows_for_qdistr 20554 20554 0 0.00 0 14199 165 0 0
sp_pay_from_customer 7048 7048 0 0.00 4 31585 103 0 0
sp_pay_to_supplier 5834 5834 0 0.00 4 15317 122 0 0
sp_payment_common 12882 12882 0 0.00 2 31571 28 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 13783 309 0 0
sp_qd_handle_on_invoice_upd_sts 10490 10490 0 0.00 0 25994 372 0 0
sp_qd_handle_on_reserve_upd_sts 9976 9976 0 0.00 0 14540 69 0 0
sp_reserve_write_off 9976 9976 0 0.00 5 14564 180 0 0
sp_supplier_invoice 5478 5478 0 0.00 6 100739 3577 0 0
sp_supplier_order 5605 5605 0 0.00 5 26942 1241 0 0
srv_aggregate_perf_data 98 98 0 0.00 2654 144501 18572 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1253 128761 41035 0 0
srv_find_qd_qs_mism 1023568 1023568 0 0.00 0 21496 7 0 0
srv_make_invnt_saldo 594 594 0 0.00 35 153259 1186 0 0
srv_make_money_saldo 794 794 0 0.00 1 31223 69 0 0
srv_recalc_idx_stat 4 4 0 0.00 184665 483130 311880 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 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 4967 4967 0 0.00 0 23344 185 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 8127 142 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 4875 19 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 626 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 3561 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 138 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 5673 33 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 28486 1609 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 3557 17 0 0
t$perf-norm:sp_client_order 20554 20554 0 0.00 0 18959 20 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 6093 20 0 0
t$perf-norm:sp_pay_from_customer 7048 7048 0 0.00 0 10850 12 0 0
t$perf-norm:sp_pay_to_supplier 5834 5834 0 0.00 0 9236 11 0 0
t$perf-norm:sp_reserve_write_off 9976 9976 0 0.00 0 9597 11 0 0
t$perf-norm:sp_supplier_invoice 5478 5478 0 0.00 0 3783 19 0 0
t$perf-norm:sp_supplier_order 5605 5605 0 0.00 0 18706 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 2897 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 1210 30 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 2361 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10110 9916 194 1.91 0 4880 18 0 194
v_all_customers 37268 37268 0 0.00 0 22 1 0 0
v_all_suppliers 30710 30710 0 0.00 0 137 1 0 0
v_all_wares 41108 41108 0 0.00 0 130 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 1831 9 0 0
v_cancel_client_order 3442 3442 0 0.00 0 4885 7 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 3037 12 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 2071 7 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 1722 7 0 0
v_cancel_supplier_invoice 906 874 32 3.53 0 276 12 0 32
v_cancel_supplier_order 836 836 0 0.00 0 1081 7 0 0
v_cancel_write_off 1986 1986 0 0.00 0 3046 19 0 0
v_max_id_clo_ord 5605 5605 0 0.00 0 365 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 2283 13 0 0
v_max_id_ord_sup 5470 5470 0 0.00 0 7617 9 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 324 3 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 7445 8 0 0
v_min_id_clo_ord 5605 5605 0 0.00 0 4354 8 0 0
v_min_id_clo_res 992 992 0 0.00 0 5415 13 0 0
v_min_id_ord_sup 5470 5470 0 0.00 0 6660 8 0 0
v_min_non_paid_invoice 5834 5834 0 0.00 0 13708 17 0 0
v_min_non_paid_realizn 7048 7048 0 0.00 0 31559 21 0 0
v_our_firm 10340 10340 0 0.00 0 25 1 0 0
v_reserve_write_off 19952 19952 0 0.00 0 3954 7 0 0
x_lock_depdocs_on_canc_invoice 11237 11237 0 0.00 0 20241 133 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 4337 412 0 0
x_make_qstorno_1000_1200 5605 5605 0 0.00 1 26918 1158 0 0
x_make_qstorno_1200_2000 5478 5478 0 0.00 3 100705 3411 0 0
x_make_qstorno_2100_3300 456932 456932 0 0.00 0 39329 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2023 19:39:47 to 12-03-2023 19:39:51.

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:49 68 8192000000 8831287424 8853037056 25414768 0.309 7 5 6 0 25688464 102240 171456
17:09:51 27055 8192000000 9887790016 10193092608 971518704 10.602 100 85 168 1 998941536 5373488 22049344
17:11:22 12638 8192000000 9929862400 10243809280 1011736912 10.992 100 88 176 0 1041794576 6990160 23067504
17:12:40 8803 8192000000 9961631440 10287009792 1046779680 11.330 100 81 162 0 1075316416 8313616 20223120
17:13:57 11489 8192000000 10012269344 10354413568 1101523984 11.852 100 75 150 0 1129685088 9721904 18439200
17:15:15 6291 8192000000 10070296464 10422104064 1155610864 12.362 100 83 164 0 1186930432 12741984 18577584
17:16:26 5477 8192000000 10105224656 10469715968 1195784192 12.737 100 72 141 0 1223384112 11470352 16129568
17:17:33 1958 8192000000 10141031728 10519543808 1237451584 13.123 100 75 148 0 1266795328 12901792 16441952
17:18:38 2038 8192000000 10177976368 10556915712 1266693216 13.391 100 81 160 0 1297071216 13948432 16429568
17:19:42 1737 8192000000 10201760384 10586525696 1290825504 13.612 100 84 164 1 1321688704 12673904 18189296
17:20:44 29736 8192000000 10206105088 10604339200 1301354048 13.708 100 50 100 1 1318979648 6545088 11080512
17:22:22 5335 8192000000 10203283232 10610274304 1313337376 13.816 100 28 57 0 1326348832 7065344 5946112
17:23:29 10909 8192000000 10201936080 10617356288 1314816480 13.830 100 15 30 0 1323328144 5331408 3180256
17:24:40 6357 8192000000 10212830384 10632048640 1327153520 13.941 100 16 32 0 1335794304 5254464 3386320
17:25:47 47783 8192000000 10244099168 10652241920 1334140992 14.005 100 52 105 0 1353395280 6581520 12672768
17:27:36 5482 8192000000 10235320112 10656780288 1340009568 14.057 100 30 60 1 1353419104 6318912 7090624
17:28:42 8564 8192000000 10221048032 10639544320 1319677776 13.874 99 18 34 0 1329046928 5474688 3894464
17:29:54 43691 8192000000 10183997216 10599084032 1247325456 13.214 95 20 40 0 1257314368 5527696 4461216
17:31:39 4980 8192000000 10148846944 10551218176 1212460720 12.892 95 26 51 0 1224750544 6608912 5680912
17:32:46 7280 8192000000 10094963824 10481410048 1196479488 12.744 96 26 52 0 1208172128 5453632 6239008
17:33:58 56690 8192000000 10149446144 10558980096 1245972480 13.201 98 28 56 0 1257811152 5220288 6618384
17:35:59 9266 8192000000 10178529728 10588413952 1286664528 13.574 100 26 52 0 1298743008 5808528 6269952
17:37:11 8837 8192000000 10190359728 10607071232 1303142288 13.724 100 23 46 0 1313817888 5005696 5669904
17:38:20 8749 8192000000 10210608288 10626842624 1320740208 13.883 100 16 32 0 1329620752 5698944 3181600
17:39:31 49943 8192000000 10239438704 10657153024 1342318464 14.078 100 39 77 0 1357616448 6557584 8740400
17:41:46 7884 8192000000 10276619456 10690818048 1362623072 14.261 100 47 94 0 1383606080 10661040 10321968
17:43:00 17912 8192000000 10275895888 10700673024 1374213504 14.365 100 40 78 1 1393174128 9884288 9076336
17:44:24 11918 8192000000 10301185840 10718846976 1386768240 14.477 100 56 112 1 1413226864 13044560 13414064
17:45:46 20816 8192000000 10303127728 10732093440 1397547952 14.573 100 41 82 0 1418894512 12096944 9249616
17:47:13 17674 8192000000 10333865328 10761342976 1413139536 14.712 100 55 109 1 1442747440 15863632 13744272
17:48:35 1574 8192000000 10354623696 10773647360 1420676000 14.779 100 87 172 0 1460387840 19150896 20560944
17:49:52 8389 8192000000 10329622656 10762735616 1411844320 14.700 100 46 91 0 1441521680 18778864 10898496
17:51:05 5267 8192000000 10342190848 10775859200 1420086320 14.773 100 47 94 0 1449553088 17778512 11688256
17:52:16 9913 8192000000 10332138704 10757083136 1403876096 14.629 100 60 118 1 1440097344 21841616 14379632
17:53:44 8641 8192000000 10343550784 10769555456 1411925168 14.701 100 61 120 0 1449670832 23651712 14093952
17:55:00 2625 8192000000 10334967264 10746372096 1393049200 14.533 100 70 136 3 1436314400 27040544 16224656
17:56:11 2142 8192000000 10321115344 10737455104 1385632736 14.467 100 67 132 1 1426717952 25303040 15782176
17:57:21 1335 8192000000 10338025552 10747068416 1387765488 14.486 99 80 159 1 1435997072 28327968 19903616
17:58:27 1301 8192000000 10347441264 10752544768 1394157040 14.543 100 82 159 1 1448843104 35050624 19635440
17:59:36 3244 8192000000 10354222928 10764062720 1397646384 14.574 100 72 142 1 1454793872 38730592 18416896
18:00:46 1834 8192000000 10375771136 10780925952 1409468608 14.679 100 81 160 0 1475892704 44823728 21600368
18:01:55 1241 8192000000 10370548384 10778619904 1405814192 14.647 100 82 162 0 1472168720 45004400 21350128
18:03:05 3723 8192000000 10383692144 10793611264 1420591776 14.778 100 78 154 1 1488510528 47441792 20476960
18:04:14 6530 8192000000 10362829680 10783608832 1401714448 14.610 100 50 100 1 1467383024 51129264 14539312
18:05:31 20205 8192000000 10345944064 10777120768 1398780768 14.584 100 36 72 2 1453783808 44442144 10560896
18:06:54 29820 8192000000 10317171136 10772045824 1375014464 14.372 100 28 56 1 1433422448 50192464 8215520
18:08:25 24833 8192000000 10318319744 10746744832 1358134944 14.221 100 32 65 1 1418289216 51318400 8835872
18:09:51 13106 8192000000 10294877728 10746937344 1367202368 14.302 100 26 52 0 1401496688 28238960 6055360
18:11:05 20915 8192000000 10259288656 10711592960 1337336432 14.033 98 8 16 0 1348542880 9767280 1439168
18:12:27 38536 8192000000 10250542176 10688131072 1328632528 13.955 98 11 22 0 1338019088 6924208 2462352
18:14:08 67584 8192000000 10257167504 10701828096 1340581488 14.063 99 9 18 0 1354346784 11740256 2025040
18:16:23 63592 8192000000 10269845456 10715549696 1358588240 14.225 99 9 16 0 1370549840 9736432 2225168
18:18:28 98863 8192000000 10294145104 10735304704 1368276816 14.312 99 26 52 1 1386215472 11392624 6546032
18:21:08 48531 8192000000 10293297984 10742362112 1373406736 14.358 99 39 77 0 1403717840 21342336 8968768
18:23:02 58738 8192000000 10299399952 10747850752 1377677184 14.396 99 13 26 0 1393042544 12380208 2985152
18:26:24 128761 8192000000 10297439168 10764144640 1388823856 14.495 100 25 51 0 1412680512 17473696 6382960
18:29:34 18649 8192000000 10307740352 10760142848 1398326896 14.580 100 30 57 2 1413789072 8686256 6775920
18:30:54 34218 8192000000 10322162832 10778181632 1411647792 14.699 100 17 34 0 1429887232 14071296 4168144
18:32:33 35655 8192000000 10333831664 10781409280 1417047424 14.747 100 35 71 0 1439406592 14361552 7997616
18:34:19 80002 8192000000 10309237824 10788245504 1412032688 14.702 100 11 23 0 1428981264 14355888 2592688
18:36:59 100572 8192000000 10313711664 10780532736 1395371136 14.554 99 24 48 0 1416324976 15762784 5191056
18:39:47 83896 8192000000 10298392160 10766512128 1398790960 14.584 99 17 34 0 1411801136 9680592 3329584
18:42:11 46051 8192000000 10297654656 10753978368 1373160112 14.355 98 28 55 1 1388519728 8963280 6396336
18:44:18 69006 8192000000 10288933408 10739650560 1353290656 14.177 97 19 36 1 1367151824 9488352 4372816
18:46:27 13191 8192000000 10306215920 10746228736 1378801376 14.406 99 51 102 0 1399088000 8832992 11453632
18:47:47 15996 8192000000 10275814576 10728988672 1369258608 14.320 99 20 40 0 1382880080 9187616 4433856
18:50:03 46648 8192000000 10267000688 10712977408 1309934352 13.785 96 23 46 0 1331968512 16773056 5261104
18:51:51 94674 8192000000 10263742192 10708783104 1293106288 13.633 94 19 38 0 1306797904 9937120 3754496
18:54:26 83065 8192000000 10254109168 10699669504 1293582384 13.637 95 15 30 0 1311443952 14331584 3529984
18:56:50 83637 8192000000 10245857648 10681864192 1283177040 13.542 95 16 32 0 1298600272 11946288 3476944
18:59:29 43205 8192000000 10242131968 10699132928 1326370400 13.934 98 13 26 0 1344071488 14707392 2993696
19:01:19 71076 8192000000 10236601584 10683957248 1324485888 13.917 98 13 26 0 1335003136 7726912 2790336
19:03:30 86313 8192000000 10251740768 10711040000 1355271248 14.195 100 14 26 0 1369520560 10819504 3429808
19:06:26 40430 8192000000 10250194688 10702618624 1343886560 14.092 99 10 20 0 1354910272 8478064 2545648
19:08:07 35919 8192000000 10268681616 10708459520 1351270320 14.159 99 24 49 0 1366834576 10283472 5280784
19:09:50 68605 8192000000 10262117872 10722230272 1341604384 14.072 98 17 33 0 1358536144 13680112 3251648
19:12:18 39466 8192000000 10273958736 10707075072 1327966096 13.949 97 25 49 0 1341281472 8377392 4937984
19:13:59 43355 8192000000 10281183504 10719477760 1344248368 14.096 98 36 70 0 1362717280 10478384 7990528
19:15:47 27352 8192000000 10282434064 10731216896 1374724512 14.369 100 37 74 1 1395961872 12488160 8749200
19:17:18 58430 8192000000 10290325424 10736947200 1343831440 14.092 97 11 22 0 1357145936 10811808 2502688
19:19:19 91651 8192000000 10295027776 10748928000 1342449280 14.079 97 16 32 0 1358028032 11387824 4190928
19:21:56 109302 8192000000 10291081424 10743115776 1362689696 14.261 98 23 45 0 1375516240 7765344 5061200
19:24:57 49511 8192000000 10271332288 10730336256 1351632416 14.162 98 9 18 0 1364263360 10639856 1991088
19:26:46 60269 8192000000 10281455744 10740539392 1374695248 14.369 99 8 16 0 1389067712 12817808 1554656
19:28:47 51277 8192000000 10272397696 10735308800 1338383136 14.043 97 6 12 0 1353118320 13335072 1400112
19:30:40 18627 8192000000 10295689984 10732941312 1368287952 14.312 99 40 80 2 1385616096 8055936 9272208
19:32:04 52019 8192000000 10292643072 10739646464 1339120304 14.049 97 20 40 0 1359396960 15403456 4873200
19:34:01 78279 8192000000 10290404288 10751254528 1355637920 14.198 98 21 42 0 1381739216 20927904 5173392
19:36:31 43860 8192000000 10282917520 10726428672 1339777568 14.055 97 23 46 1 1358438416 14012080 4648768
19:38:16 36611 8192000000 9821178880 10200522752 937867552 10.272 69 24 48 0 953083808 10249808 4966448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-03-2023 19:39:53 to 12-03-2023 19:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 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 12-03-2023 19:40:09 to 12-03-2023 19:40:11.

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          236885
MON$OLDEST_ACTIVE               238130
MON$OLDEST_SNAPSHOT             238130
MON$NEXT_TRANSACTION            238130
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                       2018880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:79: 
:80: mscs-test-161.budzdorov.ru    Sun Mar 12 19:39:00 2023
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Sun Mar 12 19:39:02 2023
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Sun Mar 12 19:39:02 2023
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Sun Mar 12 19:39:03 2023
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Sun Mar 12 19:39:03 2023
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Sun Mar 12 19:39:06 2023
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:102: 
--- end of diff output ---

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

12.03.23 19:40:12. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-12 19:40:15 No severe PSQL-related problems occured
12.03.23 19:40:16. End of report.