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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-27 19:38:22
DTS_END                         2022-09-27 19:38:22.9720
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/35378
TRN_ID                          241435
ATT_ID                          714
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: 8951.73 24645 1074207
customer order: creation 170.20 412 20424
customer order: refuse 14.71 370 1765
order to supplier: creation 45.10 1332 5412
order to supplier: removal 3.12 109677 374
invoice (draft): creation 45.43 3614 5451
invoice (draft): removal 51.14 861 6137
invoice accept: apply 41.08 16286 4930
invoice accept: cancel 45.79 849 5495
customer reserve: creation 3747.13 162 449655
customer reserve: removal 4501.48 59 540177
realization accept: apply 82.39 187 9887
realization accept: cancel 77.05 111 9246
payment from customer: creation 57.63 124 6915
payment from customer: removal 5.88 94 706
payment to supplier: creation 48.38 134 5806
payment to supplier: removal 3.28 70 394
service: total inventory turnovers 4.34 1255 521
service: total monetary turnovers 6.63 35 795
service: aggregate perf. data 0.94 15478 113
service: refresh index statistics 0.03 341785 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2022 19:39:34 to 27-09-2022 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2213
TEST_TIME 2 3286
TEST_TIME 3 2661
TEST_TIME 4 3068
TEST_TIME 5 3396
TEST_TIME 6 3577
TEST_TIME 7 3940
TEST_TIME 8 3889
TEST_TIME 9 4014
TEST_TIME 10 4216
TEST_TIME 11 4414
TEST_TIME 12 4607
TEST_TIME 13 5120
TEST_TIME 14 5531
TEST_TIME 15 5650
TEST_TIME 16 6513
TEST_TIME 17 7140
TEST_TIME 18 7408
TEST_TIME 19 7563
TEST_TIME 20 7634
TEST_TIME 21 8014
TEST_TIME 22 8317
TEST_TIME 23 8772
TEST_TIME 24 9061
TEST_TIME 25 9191
TEST_TIME 26 9771
TEST_TIME 27 9794
TEST_TIME 28 9658
TEST_TIME 29 10292
TEST_TIME 30 10109
TEST_TIME 31 10159
TEST_TIME 32 9971
TEST_TIME 33 9964
TEST_TIME 34 9763
TEST_TIME 35 9683
TEST_TIME 36 9605
TEST_TIME 37 9515
TEST_TIME 38 9479
TEST_TIME 39 9350
TEST_TIME 40 9420
TEST_TIME 41 9287
TEST_TIME 42 9236
TEST_TIME 43 9174
TEST_TIME 44 9060
TEST_TIME 45 8985
TEST_TIME 46 8848
TEST_TIME 47 8914
TEST_TIME 48 8836
TEST_TIME 49 8786
TEST_TIME 50 8799
TEST_TIME 51 8872
TEST_TIME 52 8896
TEST_TIME 53 8910
TEST_TIME 54 8954
TEST_TIME 55 8908
TEST_TIME 56 9059
TEST_TIME 57 9060
TEST_TIME 58 9064
TEST_TIME 59 9108
TEST_TIME 60 9144
TEST_TIME 61 9161
TEST_TIME 62 9261
TEST_TIME 63 9306
TEST_TIME 64 9180
TEST_TIME 65 9087
TEST_TIME 66 9091
TEST_TIME 67 9089
TEST_TIME 68 9020
TEST_TIME 69 8964
TEST_TIME 70 8958
TEST_TIME 71 9000
TEST_TIME 72 8962
TEST_TIME 73 8968
TEST_TIME 74 9122
TEST_TIME 75 9119
TEST_TIME 76 9144
TEST_TIME 77 9122
TEST_TIME 78 9170
TEST_TIME 79 9149
TEST_TIME 80 9264
TEST_TIME 81 9219
TEST_TIME 82 9291
TEST_TIME 83 9242
TEST_TIME 84 9142
TEST_TIME 85 9150
TEST_TIME 86 9122
TEST_TIME 87 9088
TEST_TIME 88 9130
TEST_TIME 89 9090
TEST_TIME 90 9062
TEST_TIME 91 9033
TEST_TIME 92 8989
TEST_TIME 93 9010
TEST_TIME 94 8994
TEST_TIME 95 9027
TEST_TIME 96 9026
TEST_TIME 97 9071
TEST_TIME 98 9122
TEST_TIME 99 9120
TEST_TIME 100 9142
TEST_TIME 101 9115
TEST_TIME 102 9088
TEST_TIME 103 9138
TEST_TIME 104 9177
TEST_TIME 105 9223
TEST_TIME 106 9283
TEST_TIME 107 9234
TEST_TIME 108 9158
TEST_TIME 109 9112
TEST_TIME 110 9109
TEST_TIME 111 9094
TEST_TIME 112 9047
TEST_TIME 113 9018
TEST_TIME 114 9035
TEST_TIME 115 9029
TEST_TIME 116 8988
TEST_TIME 117 8949
TEST_TIME 118 8976
TEST_TIME 119 9010
TEST_TIME 120 8953

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2022 19:39:38 to 27-09-2022 19:39:41.

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 1492465 1492465 0 0.00 0 20604 6 0 0
doc_list_biud 1492427 1492427 0 0.00 0 34316 28 0 0
sp_add_invoice_to_stock 5062 4930 132 2.60 30 158324 16287 0 132
sp_cancel_adding_invoice 5495 5495 0 0.00 4 69774 850 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 17135 371 0 0
sp_cancel_customer_reserve 540177 540177 0 0.00 0 24016 60 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 7305 95 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 1658 71 0 0
sp_cancel_supplier_invoice 6146 6137 9 0.14 1 31044 861 0 9
sp_cancel_supplier_order 374 374 0 0.00 8 1068618 109678 0 0
sp_cancel_write_off 9246 9246 0 0.00 1 23631 112 0 0
sp_client_order 20424 20424 0 0.00 3 22484 413 0 0
sp_customer_reserve 449655 449655 0 0.00 0 35563 163 0 0
sp_fill_shopping_cart 31286 31286 0 0.00 1 28455 41 0 0
sp_fill_shopping_cart_clo_res 449653 449653 0 0.00 0 19889 14 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 19279 283 0 0
sp_kill_cost_storno 15841 15841 0 0.00 0 7553 53 0 0
sp_kill_qstorno_ret_qs2qd 1096111 1096111 0 0.00 0 30980 33 0 0
sp_kill_qty_storno 577986 577986 0 0.00 0 34316 71 0 0
sp_lock_selected_doc 20573 20573 0 0.00 0 8543 4 0 0
sp_make_cost_storno 27535 27535 0 0.00 0 11719 65 0 0
sp_multiply_rows_for_pdistr 27497 27497 0 0.00 0 5825 25 0 0
sp_multiply_rows_for_qdistr 20424 20424 0 0.00 0 10552 176 0 0
sp_pay_from_customer 6915 6915 0 0.00 4 10319 125 0 0
sp_pay_to_supplier 5806 5806 0 0.00 4 9456 135 0 0
sp_payment_common 12705 12705 0 0.00 1 9598 24 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 17129 343 0 0
sp_qd_handle_on_invoice_upd_sts 10410 10410 0 0.00 0 34315 351 0 0
sp_qd_handle_on_reserve_upd_sts 9877 9877 0 0.00 0 15422 71 0 0
sp_reserve_write_off 9887 9887 0 0.00 6 15485 188 0 0
sp_supplier_invoice 5451 5451 0 0.00 6 98199 3614 0 0
sp_supplier_order 5412 5412 0 0.00 4 32370 1332 0 0
srv_aggregate_perf_data 113 113 0 0.00 2580 129925 15478 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2211 173272 45949 0 0
srv_find_qd_qs_mism 1028305 1028305 0 0.00 0 20603 5 0 0
srv_make_invnt_saldo 521 521 0 0.00 36 94848 1255 0 0
srv_make_money_saldo 795 795 0 0.00 0 5517 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 265440 449418 341785 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4930 4930 0 0.00 0 20272 193 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 4890 92 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 8765 29 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 4457 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 2660 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 139 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 2022 15 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 31570 2150 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 5260 14 0 0
t$perf-norm:sp_client_order 20424 20424 0 0.00 0 12058 23 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 5603 14 0 0
t$perf-norm:sp_pay_from_customer 6915 6915 0 0.00 0 17574 13 0 0
t$perf-norm:sp_pay_to_supplier 5806 5806 0 0.00 0 6966 17 0 0
t$perf-norm:sp_reserve_write_off 9887 9887 0 0.00 0 7924 16 0 0
t$perf-norm:sp_supplier_invoice 5451 5451 0 0.00 0 7944 26 0 0
t$perf-norm:sp_supplier_order 5412 5412 0 0.00 0 7318 21 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 443 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 648 11 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 6632 34 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 996 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10094 9830 264 2.61 0 6691 19 0 264
v_all_customers 36906 36906 0 0.00 0 27 1 0 0
v_all_suppliers 30132 30132 0 0.00 0 30 1 0 0
v_all_wares 40848 40848 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 5580 13 0 0
v_cancel_client_order 3530 3530 0 0.00 0 2721 5 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 2552 11 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 2138 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 993 5 0 0
v_cancel_supplier_invoice 900 882 18 2.00 0 1520 15 0 18
v_cancel_supplier_order 748 748 0 0.00 0 648 4 0 0
v_cancel_write_off 1980 1980 0 0.00 0 6339 18 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 622 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 6123 17 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 2587 2 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 2054 4 0 0
v_max_non_paid_realizn 3776 3776 0 0.00 0 2166 4 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 5956 7 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 5273 22 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 4235 8 0 0
v_min_non_paid_invoice 5796 5796 0 0.00 0 9156 19 0 0
v_min_non_paid_realizn 6909 6909 0 0.00 0 9591 18 0 0
v_our_firm 10208 10208 0 0.00 0 19 1 0 0
v_reserve_write_off 19754 19754 0 0.00 0 6145 7 0 0
x_lock_depdocs_on_canc_invoice 11191 11191 0 0.00 0 20970 115 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 7970 363 0 0
x_make_qstorno_1000_1200 5412 5412 0 0.00 1 32297 1257 0 0
x_make_qstorno_1200_2000 5451 5451 0 0.00 3 98184 3463 0 0
x_make_qstorno_2100_3300 449655 449655 0 0.00 0 34832 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2022 19:39:42 to 27-09-2022 19:39:46.

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:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:28 19824 8192000000 9886902656 10193874944 964068720 10.529 100 89 178 0 993763376 5744448 23950208
17:10:58 16442 8192000000 9942364400 10262601728 1026946416 11.139 100 84 168 0 1056139968 7835456 21358096
17:12:16 18358 8192000000 9975837440 10308849664 1061669056 11.472 100 79 158 0 1091146320 9210528 20266736
17:13:42 8450 8192000000 10028326352 10378080256 1121728144 12.043 100 75 150 0 1148999200 9362048 17909008
17:14:53 8961 8192000000 10072854000 10432172032 1160384960 12.407 100 76 152 0 1189565792 11328368 17852464
17:16:06 2521 8192000000 10123437808 10492928000 1217069264 12.935 100 85 170 1 1248645488 12738800 18837424
17:17:12 1356 8192000000 10168267008 10542616576 1249240720 13.231 100 84 168 1 1281699296 14218624 18239952
17:18:15 1429 8192000000 10182049840 10565877760 1277664080 13.492 100 76 150 0 1306491312 12763872 16063360
17:19:17 1338 8192000000 10220091232 10607271936 1309080656 13.778 100 88 176 1 1341797552 13092800 19624096
17:20:19 8732 8192000000 10199282752 10611597312 1313819744 13.821 100 34 68 0 1327747248 5929792 7997712
17:21:32 5780 8192000000 10206416544 10619555840 1318968832 13.867 100 24 50 3 1329833440 5991424 4873184
17:22:38 9250 8192000000 10211449392 10633371648 1331021872 13.976 100 18 37 0 1341976176 6889856 4064448
17:23:54 73452 8192000000 10224139408 10650677248 1338927488 14.048 100 16 31 0 1346883664 4707648 3248528
17:26:09 4131 8192000000 10239041328 10655055872 1340665008 14.063 100 24 48 0 1352033232 6188288 5179936
17:27:15 9207 8192000000 10232614496 10653667328 1341352592 14.070 100 20 38 0 1350959568 5636128 3970848
17:28:29 49298 8192000000 10181170816 10588618752 1206899456 12.840 92 39 79 2 1222224912 4773232 10552224
17:30:32 5292 8192000000 10153705728 10557034496 1231898896 13.072 97 22 45 1 1242647664 5198448 5550320
17:31:38 7925 8192000000 10148412768 10547359744 1251663792 13.254 100 20 40 0 1261341312 5471152 4206368
17:32:46 6220 8192000000 10146696032 10551005184 1256793872 13.301 100 15 30 0 1265568848 5676832 3098144
17:33:57 54986 8192000000 10154969088 10566189056 1247296208 13.213 98 30 58 0 1258770736 5135120 6339408
17:35:52 11259 8192000000 10185244752 10597785600 1293574960 13.637 100 18 36 0 1303069888 5148512 4346416
17:37:04 11346 8192000000 10209273248 10617606144 1308036544 13.768 100 22 44 0 1318503552 4831296 5635712
17:38:16 26529 8192000000 10236561008 10642354176 1331174128 13.978 100 51 100 0 1349545984 5412064 12959792
17:40:21 70780 8192000000 10248220416 10669252608 1344029520 14.094 100 31 63 0 1361852864 10751824 7071520
17:42:40 11561 8192000000 10277411408 10693808128 1368974208 14.318 100 51 102 0 1396120080 15636992 11508880
17:44:01 11017 8192000000 10302975680 10721656832 1385096736 14.462 100 53 103 0 1418116000 21026544 11992720
17:45:20 11570 8192000000 10320422864 10741972992 1399181856 14.588 100 50 99 0 1432612608 21598112 11832640
17:46:38 2211 8192000000 10330959104 10753204224 1413422816 14.714 100 62 122 1 1448375104 20174256 14778032
17:47:47 9718 8192000000 10341260864 10765312000 1414887472 14.727 100 55 110 2 1457125376 28388080 13849824
17:49:01 2569 8192000000 10352601024 10772627456 1407058528 14.658 99 52 101 1 1450130352 30431968 12639856
17:50:08 9263 8192000000 10346894976 10759786496 1404395248 14.634 99 64 124 1 1453131680 33203248 15533184
17:51:25 5226 8192000000 10368542336 10781659136 1419515408 14.768 100 76 154 0 1479043344 40235248 19292688
17:52:38 4657 8192000000 10351001920 10768138240 1397200368 14.570 100 69 131 0 1454758992 40154416 17404208
17:53:49 3404 8192000000 10351809040 10771886080 1396422112 14.563 100 63 122 0 1459821808 47164256 16235440
17:55:00 4571 8192000000 10353871616 10768121856 1392598064 14.529 100 84 167 0 1457913376 43691936 21623376
17:56:09 2469 8192000000 10327897424 10736406528 1370962240 14.336 100 79 153 1 1427483680 36314000 20207440
17:57:18 3841 8192000000 10319724592 10733477888 1360330272 14.240 98 59 117 0 1412505440 37553008 14622160
17:58:32 3796 8192000000 10342553824 10762649600 1392849792 14.531 100 60 120 1 1450779648 41927024 16002832
17:59:38 7345 8192000000 10310366608 10735308800 1358420432 14.223 99 49 98 1 1413833408 42462208 12950768
18:00:54 5356 8192000000 10322737904 10746564608 1375619184 14.377 99 48 94 0 1428451120 40867776 11964160
18:02:09 10867 8192000000 10331406416 10758172672 1388312112 14.491 100 39 74 0 1438161472 40087584 9761776
18:03:36 7458 8192000000 10307894864 10730176512 1362460144 14.259 100 36 72 0 1409260000 37054000 9745856
18:04:49 26709 8192000000 10275608880 10721665024 1329189472 13.960 98 16 32 0 1370366736 36626576 4550688
18:06:54 16330 8192000000 10297745648 10732670976 1361829456 14.254 100 26 51 0 1404524912 36084288 6611168
18:08:13 19887 8192000000 10264046880 10699354112 1340586480 14.063 99 15 30 0 1359490784 15366752 3537552
18:09:33 67193 8192000000 10274669616 10718916608 1357354336 14.214 100 28 56 1 1379714704 15703808 6656560
18:11:48 79944 8192000000 10279860160 10720362496 1357863184 14.218 99 27 55 1 1372621024 8274416 6483424
18:14:08 56533 8192000000 10272945520 10716200960 1367948944 14.309 100 14 28 1 1377531216 6508832 3073440
18:16:06 83431 8192000000 10298849632 10738061312 1383664432 14.449 100 22 45 0 1396395712 8162624 4568656
18:18:30 33536 8192000000 10273035360 10731225088 1376249264 14.383 100 7 14 0 1385852496 8100928 1502304
18:20:04 66959 8192000000 10281307632 10732519424 1384422512 14.456 100 15 30 0 1396853104 9302848 3127744
18:22:12 58347 8192000000 10302428544 10755014656 1393713904 14.539 100 9 19 0 1403245072 7070544 2460624
18:24:12 67777 8192000000 10291799392 10742722560 1374983920 14.372 99 36 69 2 1395909824 12029968 8895936
18:26:30 42833 8192000000 10293599200 10750586880 1394471456 14.546 100 24 48 1 1413894576 13358656 6064464
18:28:19 49655 8192000000 10288978880 10754498560 1380191168 14.418 99 10 20 0 1396480832 14063328 2226336
18:30:27 43904 8192000000 10303046096 10760757248 1388871952 14.496 99 21 42 1 1404903488 10480944 5550592
18:32:29 46213 8192000000 10286347616 10730381312 1354144160 14.185 98 18 36 0 1371163600 12786480 4232960
18:34:17 66687 8192000000 10294148464 10741964800 1361206320 14.248 98 16 31 1 1379253312 14398320 3648672
18:36:25 54576 8192000000 10274599568 10725896192 1327558416 13.945 96 23 46 0 1344856800 12215248 5083136
18:38:26 66805 8192000000 10271619280 10718887936 1329621728 13.964 97 11 22 0 1345988368 14074160 2292480
18:40:46 143513 8192000000 10265153072 10710573056 1333715360 14.001 97 11 20 0 1349138704 13188576 2234768
18:44:20 43923 8192000000 10246012128 10701549568 1345891152 14.110 99 22 44 0 1361582944 10478736 5213056
18:46:08 52767 8192000000 10245356848 10701852672 1323764976 13.911 98 9 18 0 1336766048 10916240 2084832
18:48:32 80476 8192000000 10250363328 10706804736 1328676656 13.955 98 17 35 0 1350254128 17770928 3806544
18:50:53 64325 8192000000 10265202544 10713116672 1324237024 13.915 97 15 30 0 1343323264 15696160 3390080
18:53:23 79261 8192000000 10240218768 10686369792 1269324784 13.415 94 15 30 0 1284404432 11530048 3549600
18:55:43 129263 8192000000 10284031664 10735652864 1314792384 13.830 96 16 32 0 1335725808 17455440 3477984
18:59:56 132038 8192000000 10252615536 10701299712 1294218848 13.643 95 25 48 2 1319578144 19956032 5403264
19:03:27 30165 8192000000 10259899568 10693836800 1320496832 13.881 98 24 48 0 1334514624 8708192 5309600
19:05:06 23970 8192000000 10261429008 10704863232 1338405472 14.043 99 30 60 0 1360107648 15099808 6602368
19:06:31 36645 8192000000 10250418848 10710347776 1359911248 14.237 100 12 24 1 1370363200 8115680 2336272
19:08:10 65608 8192000000 10277159984 10732564480 1376564224 14.386 100 16 32 0 1387840608 7160528 4115856
19:10:16 30367 8192000000 10280033744 10738561024 1385441488 14.465 100 10 16 0 1393894928 6874144 1579296
19:11:51 55809 8192000000 10288679904 10745061376 1352394960 14.169 97 8 16 0 1367584976 13855136 1334880
19:14:12 48304 8192000000 10306638960 10752638976 1384058496 14.453 99 15 28 1 1403596624 15353104 4185024
19:16:12 74305 8192000000 10290407200 10756919296 1380701872 14.423 99 14 24 0 1392716144 9543088 2471184
19:18:27 77487 8192000000 10285396480 10736648192 1338080704 14.040 96 12 24 0 1349257136 8225024 2951408
19:20:44 22477 8192000000 10295514160 10736889856 1362646224 14.261 98 45 90 0 1383702752 11143808 9912720
19:22:10 85021 8192000000 10293620176 10754449408 1350903920 14.156 97 18 36 0 1369291072 14458480 3928672
19:24:39 173271 8192000000 10285289952 10734587904 1356204352 14.203 98 23 46 0 1377738224 16092880 5440992
19:28:32 63677 8192000000 10269306816 10743812096 1371541664 14.341 100 8 14 1 1393792848 20708656 1542528
19:31:28 49045 8192000000 10265658960 10729398272 1339176176 14.050 97 17 32 0 1353570688 10593104 3801408
19:33:24 58643 8192000000 10272665040 10728337408 1345925360 14.111 98 25 50 1 1361692320 9865472 5901488
19:35:33 81191 8192000000 10265656848 10724446208 1324791168 13.920 96 24 48 0 1346923152 16282496 5849488
19:37:55 31673 8192000000 8925976800 9093611520 105348512 1.269 8 2 4 0 106755728 1066960 340256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-09-2022 19:39:49 to 27-09-2022 19:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2022 19:40:05 to 27-09-2022 19:40:07.

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          241860
MON$OLDEST_ACTIVE               241861
MON$OLDEST_SNAPSHOT             241861
MON$NEXT_TRANSACTION            241861
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                       2017984
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
--- end of diff output ---

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

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