Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2023 01: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 15202024 kB
MemAvailable 30387292 kB
Buffers 0 kB
Cached 16486804 kB
SwapCached 4540 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-01-12 01:38:36
DTS_END                         2023-01-12 01:38:36.4580
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/33190
TRN_ID                          249696
ATT_ID                          722
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9443.94 19730 1133273
customer order: creation 181.99 365 21839
customer order: refuse 16.00 339 1920
order to supplier: creation 47.40 1389 5688
order to supplier: removal 3.48 99141 417
invoice (draft): creation 48.29 3301 5795
invoice (draft): removal 53.60 817 6432
invoice accept: apply 42.95 16917 5154
invoice accept: cancel 48.78 816 5853
customer reserve: creation 3941.12 170 472934
customer reserve: removal 4751.69 57 570203
realization accept: apply 85.90 178 10308
realization accept: cancel 87.06 103 10447
payment from customer: creation 61.08 134 7330
payment from customer: removal 6.13 84 735
payment to supplier: creation 51.97 150 6236
payment to supplier: removal 3.53 79 423
service: total inventory turnovers 5.12 952 614
service: total monetary turnovers 6.98 21 838
service: aggregate perf. data 0.86 20225 103
service: refresh index statistics 0.03 249353 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2023 01:39:25 to 12-01-2023 01:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5835
TEST_TIME 2 3930
TEST_TIME 3 4302
TEST_TIME 4 4529
TEST_TIME 5 4630
TEST_TIME 6 5132
TEST_TIME 7 5172
TEST_TIME 8 5239
TEST_TIME 9 5224
TEST_TIME 10 5373
TEST_TIME 11 5591
TEST_TIME 12 5659
TEST_TIME 13 5594
TEST_TIME 14 5525
TEST_TIME 15 5559
TEST_TIME 16 5668
TEST_TIME 17 5915
TEST_TIME 18 6614
TEST_TIME 19 6945
TEST_TIME 20 7080
TEST_TIME 21 7165
TEST_TIME 22 7409
TEST_TIME 23 7760
TEST_TIME 24 8064
TEST_TIME 25 8921
TEST_TIME 26 9585
TEST_TIME 27 10082
TEST_TIME 28 9825
TEST_TIME 29 9773
TEST_TIME 30 9862
TEST_TIME 31 10117
TEST_TIME 32 10266
TEST_TIME 33 10350
TEST_TIME 34 10255
TEST_TIME 35 10161
TEST_TIME 36 10169
TEST_TIME 37 10038
TEST_TIME 38 10147
TEST_TIME 39 10138
TEST_TIME 40 10127
TEST_TIME 41 10025
TEST_TIME 42 10021
TEST_TIME 43 10129
TEST_TIME 44 10129
TEST_TIME 45 10001
TEST_TIME 46 9795
TEST_TIME 47 9867
TEST_TIME 48 9793
TEST_TIME 49 9833
TEST_TIME 50 9735
TEST_TIME 51 9714
TEST_TIME 52 9674
TEST_TIME 53 9639
TEST_TIME 54 9643
TEST_TIME 55 9624
TEST_TIME 56 9592
TEST_TIME 57 9539
TEST_TIME 58 9515
TEST_TIME 59 9455
TEST_TIME 60 9447
TEST_TIME 61 9421
TEST_TIME 62 9426
TEST_TIME 63 9376
TEST_TIME 64 9382
TEST_TIME 65 9444
TEST_TIME 66 9504
TEST_TIME 67 9514
TEST_TIME 68 9541
TEST_TIME 69 9551
TEST_TIME 70 9601
TEST_TIME 71 9662
TEST_TIME 72 9667
TEST_TIME 73 9682
TEST_TIME 74 9724
TEST_TIME 75 9690
TEST_TIME 76 9640
TEST_TIME 77 9556
TEST_TIME 78 9531
TEST_TIME 79 9476
TEST_TIME 80 9440
TEST_TIME 81 9447
TEST_TIME 82 9394
TEST_TIME 83 9316
TEST_TIME 84 9301
TEST_TIME 85 9273
TEST_TIME 86 9441
TEST_TIME 87 9398
TEST_TIME 88 9374
TEST_TIME 89 9446
TEST_TIME 90 9425
TEST_TIME 91 9401
TEST_TIME 92 9447
TEST_TIME 93 9458
TEST_TIME 94 9441
TEST_TIME 95 9523
TEST_TIME 96 9531
TEST_TIME 97 9502
TEST_TIME 98 9601
TEST_TIME 99 9584
TEST_TIME 100 9530
TEST_TIME 101 9482
TEST_TIME 102 9478
TEST_TIME 103 9442
TEST_TIME 104 9432
TEST_TIME 105 9448
TEST_TIME 106 9407
TEST_TIME 107 9371
TEST_TIME 108 9363
TEST_TIME 109 9340
TEST_TIME 110 9389
TEST_TIME 111 9389
TEST_TIME 112 9403
TEST_TIME 113 9380
TEST_TIME 114 9407
TEST_TIME 115 9385
TEST_TIME 116 9427
TEST_TIME 117 9450
TEST_TIME 118 9463
TEST_TIME 119 9480
TEST_TIME 120 9444

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2023 01:39:29 to 12-01-2023 01:39:32.

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 1572028 1572028 0 0.00 0 26237 6 0 0
doc_list_biud 1572027 1572027 0 0.00 0 58797 26 0 0
sp_add_invoice_to_stock 5296 5154 142 2.68 47 147833 16917 0 142
sp_cancel_adding_invoice 5853 5853 0 0.00 4 62970 817 0 0
sp_cancel_client_order 1920 1920 0 0.00 4 12577 340 0 0
sp_cancel_customer_reserve 570203 570203 0 0.00 0 58800 57 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 7330 84 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 7788 80 0 0
sp_cancel_supplier_invoice 6447 6432 15 0.23 2 14415 818 0 15
sp_cancel_supplier_order 417 417 0 0.00 6 1035542 99141 0 0
sp_cancel_write_off 10447 10447 0 0.00 1 17940 104 0 0
sp_client_order 21839 21839 0 0.00 3 18177 365 0 0
sp_customer_reserve 472934 472934 0 0.00 0 67205 171 0 0
sp_fill_shopping_cart 33309 33309 0 0.00 1 8265 36 0 0
sp_fill_shopping_cart_clo_res 472932 472932 0 0.00 0 16160 12 0 0
sp_get_clo_for_invoice 5152 5152 0 0.00 0 20022 229 0 0
sp_kill_cost_storno 17458 17458 0 0.00 0 17931 51 0 0
sp_kill_qstorno_ret_qs2qd 1157702 1157702 0 0.00 0 58779 31 0 0
sp_kill_qty_storno 610732 610732 0 0.00 0 58796 67 0 0
sp_lock_selected_doc 21557 21557 0 0.00 0 9782 7 0 0
sp_make_cost_storno 29026 29026 0 0.00 0 26231 47 0 0
sp_multiply_rows_for_pdistr 29026 29026 0 0.00 0 11997 31 0 0
sp_multiply_rows_for_qdistr 21839 21839 0 0.00 0 15557 137 0 0
sp_pay_from_customer 7330 7330 0 0.00 4 14937 134 0 0
sp_pay_to_supplier 6236 6236 0 0.00 4 26257 150 0 0
sp_payment_common 13566 13566 0 0.00 2 10509 32 0 0
sp_qd_handle_on_cancel_clo 1920 1920 0 0.00 0 12569 310 0 0
sp_qd_handle_on_invoice_upd_sts 11005 11005 0 0.00 0 27734 304 0 0
sp_qd_handle_on_reserve_upd_sts 10308 10308 0 0.00 0 17357 60 0 0
sp_reserve_write_off 10308 10308 0 0.00 6 17402 178 0 0
sp_supplier_invoice 5795 5795 0 0.00 6 96182 3302 0 0
sp_supplier_order 5688 5688 0 0.00 5 28780 1389 0 0
srv_aggregate_perf_data 103 103 0 0.00 2853 536188 20226 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 994 159811 46091 0 0
srv_find_qd_qs_mism 1084023 1084023 0 0.00 0 10882 5 0 0
srv_make_invnt_saldo 614 614 0 0.00 54 37579 953 0 0
srv_make_money_saldo 838 838 0 0.00 1 437 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 70962 558751 249354 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5155 5155 0 0.00 0 20823 147 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 11670 144 0 0
t$perf-norm:sp_cancel_client_order 1920 1920 0 0.00 0 7176 20 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 2030 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 1891 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 446 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1480 20 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 29365 1649 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 8132 14 0 0
t$perf-norm:sp_client_order 21839 21839 0 0.00 0 9202 16 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 1007 4 0 0
t$perf-norm:sp_pay_from_customer 7330 7330 0 0.00 0 5008 11 0 0
t$perf-norm:sp_pay_to_supplier 6236 6236 0 0.00 0 6707 11 0 0
t$perf-norm:sp_reserve_write_off 10308 10308 0 0.00 0 8203 13 0 0
t$perf-norm:sp_supplier_invoice 5795 5795 0 0.00 0 10327 17 0 0
t$perf-norm:sp_supplier_order 5688 5688 0 0.00 0 9093 14 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 192 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 121 3 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 1483 17 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 2653 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10588 10304 284 2.68 0 7707 20 0 284
v_all_customers 39344 39344 0 0.00 0 18 0 0 0
v_all_suppliers 32102 32102 0 0.00 0 22 1 0 0
v_all_wares 43678 43678 0 0.00 0 93 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 4777 19 0 0
v_cancel_client_order 3840 3840 0 0.00 0 2082 6 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 3641 10 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 5569 13 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 2889 13 0 0
v_cancel_supplier_invoice 854 824 30 3.51 0 1159 18 0 30
v_cancel_supplier_order 834 834 0 0.00 0 1048 7 0 0
v_cancel_write_off 2114 2114 0 0.00 0 3138 15 0 0
v_max_id_clo_ord 5688 5688 0 0.00 0 8256 3 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 1931 11 0 0
v_max_id_ord_sup 5782 5782 0 0.00 0 5924 6 0 0
v_max_non_paid_invoice 1655 1655 0 0.00 0 1594 4 0 0
v_max_non_paid_realizn 3964 3964 0 0.00 0 6136 8 0 0
v_min_id_clo_ord 5688 5688 0 0.00 0 7220 10 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 1444 4 0 0
v_min_id_ord_sup 5782 5782 0 0.00 0 4826 10 0 0
v_min_non_paid_invoice 6236 6236 0 0.00 0 8552 21 0 0
v_min_non_paid_realizn 7330 7330 0 0.00 0 8509 25 0 0
v_our_firm 11066 11066 0 0.00 0 20 1 0 0
v_reserve_write_off 20616 20616 0 0.00 0 3513 7 0 0
x_lock_depdocs_on_canc_invoice 11873 11873 0 0.00 0 30773 120 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 6545 328 0 0
x_make_qstorno_1000_1200 5688 5688 0 0.00 1 28772 1312 0 0
x_make_qstorno_1200_2000 5795 5795 0 0.00 3 96128 3173 0 0
x_make_qstorno_2100_3300 472934 472934 0 0.00 0 67204 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2023 01:39:33 to 12-01-2023 01:39:37.

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
23:08:40 6 8192000000 8806951968 8820924416 11575632 0.141 3 3 4 0 11759536 76800 107104
23:09:40 29291 8192000000 9900853344 10209796096 977423408 10.659 100 86 172 0 1006634768 6568080 22643280
23:11:19 25393 8192000000 9935102608 10255216640 1017871568 11.051 100 84 168 0 1048115056 8375520 21867968
23:12:48 10632 8192000000 9962998752 10292797440 1045437408 11.317 100 82 164 0 1076053280 9564880 21050992
23:14:05 5922 8192000000 10017210512 10363097088 1106961456 11.904 100 81 160 0 1136429616 10241104 19227056
23:15:15 4575 8192000000 10066817440 10422759424 1153801424 12.345 100 85 170 0 1187327072 13449408 20076240
23:16:25 3370 8192000000 10110754672 10476138496 1199620016 12.773 100 75 149 1 1229034032 12711984 16702032
23:17:31 2617 8192000000 10153258368 10525532160 1241578064 13.161 100 87 172 0 1273696032 13283264 18834704
23:18:36 2177 8192000000 10190312160 10567094272 1269225248 13.415 100 78 157 0 1299691232 13853968 16612016
23:19:39 866 8192000000 10210401920 10594926592 1300338560 13.698 100 86 170 0 1331008144 11645120 19024464
23:20:41 27184 8192000000 10219249936 10616410112 1311392768 13.799 100 64 128 1 1333805664 7612816 14800080
23:22:10 2762 8192000000 10213994800 10622136320 1320746800 13.883 100 35 70 0 1336126288 7942720 7436768
23:23:14 7666 8192000000 10214040000 10629914624 1326459760 13.935 100 19 37 0 1336359152 6247376 3652016
23:24:22 10209 8192000000 10217420688 10639896576 1333291440 13.997 100 17 34 0 1342361568 5699328 3370800
23:25:35 43747 8192000000 10246572000 10651639808 1340172192 14.059 100 57 114 0 1358772112 5080000 13519920
23:27:19 6841 8192000000 10225767168 10641494016 1321688128 13.892 99 31 62 0 1334287168 5686208 6912832
23:28:26 10022 8192000000 10212612160 10630823936 1313328976 13.816 99 18 36 0 1331453792 14409696 3715120
23:29:37 56032 8192000000 10177389280 10574123008 1250053504 13.239 96 38 76 0 1264789744 5422144 9314096
23:31:40 5530 8192000000 10123470432 10522333184 1234875344 13.099 99 30 60 1 1248689328 7070944 6743040
23:32:46 11073 8192000000 10140253072 10543628288 1251870624 13.255 100 21 43 0 1262316688 5604448 4841616
23:33:58 15229 8192000000 10157165152 10569318400 1271870352 13.439 100 18 37 0 1281527312 5348032 4308928
23:35:14 35379 8192000000 10169592832 10582511616 1284520336 13.554 100 36 72 0 1298543168 5777616 8245216
23:36:50 8988 8192000000 10199589488 10612498432 1301271904 13.707 100 34 68 0 1318909760 10845808 6792048
23:37:59 8649 8192000000 10210777344 10626035712 1315864832 13.839 100 12 24 1 1327146768 8975664 2306272
23:39:11 46200 8192000000 10239596464 10662453248 1341364736 14.070 100 31 60 0 1354482272 5927904 7189632
23:41:07 6641 8192000000 10259631856 10679934976 1353231232 14.177 100 41 81 0 1369510256 7791616 8487408
23:42:18 17787 8192000000 10274888048 10698424320 1367765216 14.307 100 38 76 0 1386650320 9887216 8997888
23:43:39 11487 8192000000 10297273824 10718216192 1382549568 14.439 100 52 104 0 1410854896 16029376 12275952
23:44:54 5343 8192000000 10296621648 10717945856 1382273216 14.437 100 47 93 2 1408901536 15931920 10696400
23:46:06 10427 8192000000 10306935696 10737811456 1396839616 14.567 100 42 84 1 1421378480 15218080 9320784
23:47:27 13358 8192000000 10316883936 10746589184 1399851024 14.594 100 44 83 2 1426683808 17606048 9226736
23:48:50 1311 8192000000 10358086528 10777161728 1419553680 14.769 100 58 115 0 1459040336 26050016 13436640
23:49:56 18561 8192000000 10342978432 10773585920 1411659616 14.699 100 40 80 0 1453959296 32461008 9838672
23:51:20 7527 8192000000 10348199568 10773516288 1414662560 14.725 100 62 124 3 1466837056 37287360 14887136
23:52:31 5576 8192000000 10356631200 10771570688 1401954672 14.612 99 67 132 1 1455844000 37652544 16236784
23:53:42 3178 8192000000 10336264128 10742075392 1382390832 14.438 99 75 143 0 1441706320 41354992 17960496
23:54:49 8450 8192000000 10347991760 10761023488 1390842576 14.513 99 66 127 3 1456217760 48311856 17063328
23:56:09 3808 8192000000 10343086912 10753683456 1380582896 14.422 99 71 142 2 1448648528 49619312 18446320
23:57:20 993 8192000000 10351500480 10762739712 1394633728 14.547 100 78 150 1 1453681904 40220320 18827856
23:58:28 14437 8192000000 10336628992 10762145792 1386507856 14.475 99 37 73 0 1434544448 38085840 9950752
23:59:49 8190 8192000000 10333781376 10752692224 1377859552 14.397 99 45 87 2 1433377600 42653248 12864800
00:01:07 7004 8192000000 10332418864 10756317184 1382358032 14.438 99 54 104 2 1435809040 39611424 13839584
00:02:22 5952 8192000000 10344405424 10769141760 1386611616 14.476 100 49 96 2 1451297408 50979376 13706416
00:03:30 23679 8192000000 10316089552 10765733888 1360213408 14.239 99 28 56 0 1411297536 42756944 8327184
00:05:17 20897 8192000000 10274361712 10717118464 1339682896 14.055 98 17 34 0 1377122784 32844224 4595664
00:06:45 56794 8192000000 10269264096 10703925248 1333024496 13.994 99 23 46 0 1366568528 27871392 5672640
00:08:44 22739 8192000000 10267499888 10716098560 1344616672 14.099 100 13 26 1 1382537248 34451952 3468624
00:10:07 30523 8192000000 10286656720 10726596608 1362444736 14.259 100 5 10 0 1384332672 21040848 847088
00:11:39 71245 8192000000 10295888432 10729631744 1373098432 14.355 100 18 36 0 1400355216 23042320 4214464
00:13:56 159803 8192000000 10296172448 10744348672 1370926176 14.335 99 8 16 0 1388610064 16029520 1654368
00:17:43 67837 8192000000 10308960144 10747297792 1387575632 14.484 100 14 28 0 1405613824 14737376 3300816
00:19:55 94514 8192000000 10313365408 10773078016 1390233744 14.508 99 25 48 0 1410108976 14250368 5624864
00:22:41 81345 8192000000 10316319840 10771668992 1378128000 14.400 98 13 24 1 1395699616 15321168 2250448
00:25:02 95683 8192000000 10324920000 10772140032 1394449632 14.546 99 28 56 1 1408216912 7453504 6313776
00:27:40 75487 8192000000 10302864304 10741198848 1351367040 14.160 96 30 61 0 1368031328 9680688 6983600
00:29:56 16464 8192000000 10303752320 10750803968 1383207440 14.445 99 45 90 1 1401252192 7020640 11024112
00:31:14 10453 8192000000 10317204160 10756055040 1391245328 14.517 100 56 111 1 1415413328 9687312 14480688
00:32:29 152912 8192000000 10263551984 10718920704 1313370496 13.817 94 28 57 0 1327656672 7872720 6413456
00:36:03 76318 8192000000 10262886448 10720022528 1351257648 14.159 98 18 36 0 1364326208 9214496 3854064
00:38:43 59281 8192000000 10285118352 10748805120 1362744512 14.262 98 13 26 0 1374116880 8541280 2831088
00:40:43 45927 8192000000 10285532224 10744418304 1348839520 14.137 97 11 22 0 1356749600 5534400 2375680
00:42:30 50299 8192000000 10278025536 10737344512 1334782048 14.010 97 22 43 0 1351372576 11134080 5456448
00:44:21 56139 8192000000 10270171328 10720256000 1319818192 13.875 96 17 34 2 1338048480 14177632 4052656
00:46:18 45164 8192000000 10273743104 10715570176 1312978368 13.813 96 28 56 0 1329637872 10357664 6301840
00:48:20 105757 8192000000 10250277088 10697555968 1237503520 13.123 91 16 32 0 1251641312 10704128 3433664
00:51:07 48055 8192000000 10265766240 10717016064 1342732672 14.082 99 28 56 0 1360112832 11194576 6185584
00:52:55 56402 8192000000 10258685728 10694402048 1295368896 13.653 96 31 61 0 1311103232 8838640 6895696
00:54:55 78538 8192000000 10251444688 10695233536 1330316096 13.970 99 31 62 0 1346409920 9093664 7000160
00:57:37 24236 8192000000 10273428416 10708832256 1352932464 14.174 100 42 84 0 1371371248 9283488 9155296
00:59:04 70656 8192000000 10264557936 10728894464 1350605216 14.153 99 11 22 0 1363851040 10716064 2529760
01:01:15 45025 8192000000 10289664192 10735304704 1373189472 14.356 100 23 46 0 1396540544 17430192 5920880
01:03:23 73938 8192000000 10282396144 10740678656 1361220400 14.248 99 22 42 1 1392663728 26115280 5328048
01:05:51 27896 8192000000 10297234848 10745708544 1381289728 14.428 100 23 44 1 1398354944 12196272 4868944
01:07:19 84473 8192000000 10300486688 10764816384 1371626528 14.342 99 10 20 0 1385846096 12070704 2148864
01:09:43 26346 8192000000 10309356048 10753499136 1377657008 14.396 98 33 65 1 1394266768 9781024 6828736
01:11:14 50778 8192000000 10309285152 10774999040 1386843936 14.478 99 18 36 1 1405023968 13866480 4313552
01:13:15 79687 8192000000 10313316160 10782392320 1391874880 14.523 99 4 8 1 1403428896 10864128 689888
01:15:35 37551 8192000000 10327458720 10775584768 1393132480 14.534 99 24 48 0 1412410496 13563744 5714272
01:17:18 130798 8192000000 10263862656 10709536768 1245766480 13.199 89 13 26 0 1258921696 9962768 3192448
01:20:33 43582 8192000000 10277948800 10732498944 1368168912 14.311 100 26 51 0 1382787184 9146256 5472016
01:22:22 41807 8192000000 10289683648 10747432960 1376014080 14.381 100 25 48 1 1392474448 10781152 5679216
01:24:42 45542 8192000000 10285856592 10741583872 1368765776 14.316 99 15 30 0 1383991200 11851632 3373792
01:26:29 79509 8192000000 10286288128 10747600896 1376270336 14.383 100 11 22 0 1398162592 19076384 2815872
01:29:01 104653 8192000000 10249849840 10706882560 1294297248 13.643 94 10 18 0 1306404304 9825552 2281504
01:31:46 25661 8192000000 10268079616 10718924800 1349443216 14.142 99 29 58 0 1367443600 11609168 6391216
01:33:23 18556 8192000000 10272468896 10708213760 1349316144 14.141 99 35 70 0 1366227040 9357152 7553744
01:34:44 57479 8192000000 10278669600 10723110912 1315159024 13.833 96 22 45 0 1339191488 19315680 4716784
01:36:49 83089 8192000000 10236890032 10663051264 1222208352 12.982 91 12 22 0 1245054944 20443456 2403136

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-01-2023 01:39:39 to 12-01-2023 01:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2023 01:39:55 to 12-01-2023 01:39:57.

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          248264
MON$OLDEST_ACTIVE               249827
MON$OLDEST_SNAPSHOT             249827
MON$NEXT_TRANSACTION            249827
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                       2039904
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Jan 12 01:38:41 2023
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Jan 12 01:38:50 2023
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Jan 12 01:38:51 2023
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
--- end of diff output ---

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

12.01.23 01:39:58. 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-01-12 01:40:01 No severe PSQL-related problems occured
12.01.23 01:40:02. End of report.