Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2023 13:38
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 14808640 kB
MemAvailable 30398284 kB
Buffers 0 kB
Cached 16863932 kB
SwapCached 3356 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-02-11 13:37:27
DTS_END                         2023-02-11 13:37:27.0730
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(68:17)
IP                              ::1/34526
TRN_ID                          225505
ATT_ID                          620
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: 9118.98 17847 1094277
customer order: creation 177.28 445 21273
customer order: refuse 15.74 383 1889
order to supplier: creation 46.53 1356 5584
order to supplier: removal 3.53 152840 423
invoice (draft): creation 45.98 3007 5517
invoice (draft): removal 51.21 948 6145
invoice accept: apply 42.41 24812 5089
invoice accept: cancel 45.27 1167 5432
customer reserve: creation 3907.28 236 468874
customer reserve: removal 4487.29 94 538475
realization accept: apply 86.02 255 10322
realization accept: cancel 79.42 159 9530
payment from customer: creation 59.34 177 7121
payment from customer: removal 5.61 172 673
payment to supplier: creation 49.97 187 5996
payment to supplier: removal 3.15 131 378
service: total inventory turnovers 3.98 3965 478
service: total monetary turnovers 7.98 333 957
service: aggregate perf. data 0.97 14657 116
service: refresh index statistics 0.04 151614 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-02-2023 13:38:21 to 11-02-2023 13:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9857
TEST_TIME 2 8068
TEST_TIME 3 7442
TEST_TIME 4 6972
TEST_TIME 5 6687
TEST_TIME 6 6560
TEST_TIME 7 6587
TEST_TIME 8 7025
TEST_TIME 9 7430
TEST_TIME 10 7336
TEST_TIME 11 7557
TEST_TIME 12 7584
TEST_TIME 13 7704
TEST_TIME 14 7734
TEST_TIME 15 7540
TEST_TIME 16 7459
TEST_TIME 17 7587
TEST_TIME 18 7702
TEST_TIME 19 7728
TEST_TIME 20 7783
TEST_TIME 21 8030
TEST_TIME 22 8906
TEST_TIME 23 9047
TEST_TIME 24 9134
TEST_TIME 25 9118
TEST_TIME 26 9265
TEST_TIME 27 9621
TEST_TIME 28 9560
TEST_TIME 29 10132
TEST_TIME 30 10256
TEST_TIME 31 10456
TEST_TIME 32 10486
TEST_TIME 33 10494
TEST_TIME 34 10808
TEST_TIME 35 10691
TEST_TIME 36 10713
TEST_TIME 37 10796
TEST_TIME 38 11046
TEST_TIME 39 11166
TEST_TIME 40 11071
TEST_TIME 41 11035
TEST_TIME 42 10909
TEST_TIME 43 10761
TEST_TIME 44 10656
TEST_TIME 45 10600
TEST_TIME 46 10683
TEST_TIME 47 10760
TEST_TIME 48 10631
TEST_TIME 49 10500
TEST_TIME 50 10359
TEST_TIME 51 10230
TEST_TIME 52 10352
TEST_TIME 53 10286
TEST_TIME 54 10165
TEST_TIME 55 10141
TEST_TIME 56 10068
TEST_TIME 57 9985
TEST_TIME 58 9995
TEST_TIME 59 10039
TEST_TIME 60 9931
TEST_TIME 61 9886
TEST_TIME 62 9884
TEST_TIME 63 9829
TEST_TIME 64 9720
TEST_TIME 65 9699
TEST_TIME 66 9639
TEST_TIME 67 9648
TEST_TIME 68 9614
TEST_TIME 69 9554
TEST_TIME 70 9574
TEST_TIME 71 9540
TEST_TIME 72 9558
TEST_TIME 73 9564
TEST_TIME 74 9562
TEST_TIME 75 9549
TEST_TIME 76 9629
TEST_TIME 77 9670
TEST_TIME 78 9670
TEST_TIME 79 9700
TEST_TIME 80 9661
TEST_TIME 81 9576
TEST_TIME 82 9561
TEST_TIME 83 9492
TEST_TIME 84 9474
TEST_TIME 85 9454
TEST_TIME 86 9410
TEST_TIME 87 9353
TEST_TIME 88 9303
TEST_TIME 89 9327
TEST_TIME 90 9317
TEST_TIME 91 9287
TEST_TIME 92 9276
TEST_TIME 93 9234
TEST_TIME 94 9286
TEST_TIME 95 9293
TEST_TIME 96 9310
TEST_TIME 97 9419
TEST_TIME 98 9450
TEST_TIME 99 9523
TEST_TIME 100 9486
TEST_TIME 101 9453
TEST_TIME 102 9489
TEST_TIME 103 9495
TEST_TIME 104 9422
TEST_TIME 105 9365
TEST_TIME 106 9361
TEST_TIME 107 9297
TEST_TIME 108 9287
TEST_TIME 109 9248
TEST_TIME 110 9188
TEST_TIME 111 9170
TEST_TIME 112 9123
TEST_TIME 113 9127
TEST_TIME 114 9161
TEST_TIME 115 9150
TEST_TIME 116 9179
TEST_TIME 117 9176
TEST_TIME 118 9218
TEST_TIME 119 9216
TEST_TIME 120 9157

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-02-2023 13:38:25 to 11-02-2023 13:38:29.

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 1527236 1527236 0 0.00 0 18120 13 0 0
doc_list_biud 1527221 1527221 0 0.00 0 42879 39 0 0
sp_add_invoice_to_stock 5290 5089 201 3.79 75 207096 24812 0 201
sp_cancel_adding_invoice 5432 5432 0 0.00 6 84173 1168 0 0
sp_cancel_client_order 1889 1889 0 0.00 7 11545 384 0 0
sp_cancel_customer_reserve 538475 538475 0 0.00 1 27711 94 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 11537 172 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 3510 131 0 0
sp_cancel_supplier_invoice 6157 6145 12 0.19 3 15252 948 0 12
sp_cancel_supplier_order 423 423 0 0.00 16 1327198 152841 0 0
sp_cancel_write_off 9530 9530 0 0.00 2 21027 160 0 0
sp_client_order 21273 21273 0 0.00 5 17710 445 0 0
sp_customer_reserve 468874 468874 0 0.00 1 156308 237 0 0
sp_fill_shopping_cart 32363 32363 0 0.00 1 12066 79 0 0
sp_fill_shopping_cart_clo_res 468831 468831 0 0.00 0 16052 18 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 20654 264 0 0
sp_kill_cost_storno 16013 16013 0 0.00 0 11893 68 0 0
sp_kill_qstorno_ret_qs2qd 1093048 1093048 0 0.00 0 28912 41 0 0
sp_kill_qty_storno 577247 577247 0 0.00 0 42877 95 0 0
sp_lock_selected_doc 21242 21242 0 0.00 0 10940 7 0 0
sp_make_cost_storno 28470 28470 0 0.00 0 15820 80 0 0
sp_multiply_rows_for_pdistr 28469 28469 0 0.00 0 8746 37 0 0
sp_multiply_rows_for_qdistr 21273 21273 0 0.00 0 13799 169 0 0
sp_pay_from_customer 7121 7121 0 0.00 5 17816 178 0 0
sp_pay_to_supplier 5996 5996 0 0.00 6 15489 187 0 0
sp_payment_common 13115 13115 0 0.00 2 9659 46 0 0
sp_qd_handle_on_cancel_clo 1889 1889 0 0.00 0 11538 309 0 0
sp_qd_handle_on_invoice_upd_sts 10463 10463 0 0.00 0 42877 511 0 0
sp_qd_handle_on_reserve_upd_sts 10322 10322 0 0.00 0 17852 55 0 0
sp_reserve_write_off 10322 10322 0 0.00 8 17888 255 0 0
sp_supplier_invoice 5517 5517 0 0.00 9 81622 3008 0 0
sp_supplier_order 5584 5584 0 0.00 8 35269 1357 0 0
srv_aggregate_perf_data 116 116 0 0.00 2576 147034 14657 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 808 148443 34855 0 0
srv_find_qd_qs_mism 1045174 1045174 0 0.00 0 10453 10 0 0
srv_make_invnt_saldo 478 478 0 0.00 64 81559 3965 0 0
srv_make_money_saldo 957 957 0 0.00 1 8650 333 0 0
srv_recalc_idx_stat 5 5 0 0.00 31639 285425 151615 0 0
t$perf-abend:sp_add_invoice_to_stock 201 201 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5096 5096 0 0.00 1 9960 954 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 1 5845 396 0 0
t$perf-norm:sp_cancel_client_order 1889 1889 0 0.00 0 1427 29 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 4021 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 3168 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 2862 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 3529 47 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 117513 6242 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 787 18 0 0
t$perf-norm:sp_client_order 21274 21274 0 0.00 0 8274 34 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 4000 18 0 0
t$perf-norm:sp_pay_from_customer 7121 7121 0 0.00 0 11056 20 0 0
t$perf-norm:sp_pay_to_supplier 5996 5996 0 0.00 0 5237 20 0 0
t$perf-norm:sp_reserve_write_off 10322 10322 0 0.00 0 6682 25 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 3295 28 0 0
t$perf-norm:sp_supplier_order 5584 5584 0 0.00 0 6604 31 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 173 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 302 8 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 1065 15 0 0
t$perf-norm:srv_make_money_saldo 957 957 0 0.00 0 1117 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 182 102 0 0
v_add_invoice_to_stock 10462 10060 402 3.84 0 9093 26 0 402
v_all_customers 38510 38510 0 0.00 0 81 4 0 0
v_all_suppliers 31188 31188 0 0.00 0 67 4 0 0
v_all_wares 42534 42534 0 0.00 0 135 4 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 2616 18 0 0
v_cancel_client_order 3778 3778 0 0.00 0 5845 10 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 6672 24 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 1 1619 12 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 3463 19 0 0
v_cancel_supplier_invoice 892 868 24 2.69 0 6449 32 0 24
v_cancel_supplier_order 846 846 0 0.00 0 412 10 0 0
v_cancel_write_off 2096 2096 0 0.00 0 3181 26 0 0
v_max_id_clo_ord 5583 5583 0 0.00 0 2107 6 0 0
v_max_id_clo_res 966 966 0 0.00 0 2460 21 0 0
v_max_id_ord_sup 5511 5511 0 0.00 0 4938 8 0 0
v_max_non_paid_invoice 1495 1495 0 0.00 0 615 8 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 2307 9 0 0
v_min_id_clo_ord 5582 5582 0 0.00 0 3922 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 2227 9 0 0
v_min_id_ord_sup 5511 5511 0 0.00 0 4402 8 0 0
v_min_non_paid_invoice 5996 5996 0 0.00 0 4824 15 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 7288 21 0 0
v_our_firm 10900 10900 0 0.00 0 57 4 0 0
v_reserve_write_off 20644 20644 0 0.00 0 5305 11 0 0
x_lock_depdocs_on_canc_invoice 11143 11143 0 0.00 0 20432 124 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 5741 331 0 0
x_make_qstorno_1000_1200 5584 5584 0 0.00 2 35254 1198 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 4 79531 2790 0 0
x_make_qstorno_2100_3300 468872 468872 0 0.00 0 156296 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2023 13:38:30 to 11-02-2023 13:38:34.

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
11:07:31 12 8192000000 8814661232 8832282624 17048096 0.207 6 5 6 0 17398192 211952 138144
11:08:31 32203 8192000000 9734944256 10037161984 815651488 9.055 100 92 184 0 846531152 8676400 22203264
11:10:06 21058 8192000000 9771597088 10082144256 841887344 9.319 100 89 178 1 876442912 13437392 21118176
11:11:28 13617 8192000000 9804594576 10125918208 867033024 9.570 100 85 165 1 911616624 24958592 19625008
11:12:46 6927 8192000000 9855705088 10185453568 906036080 9.958 100 79 158 0 961645168 37068832 18540256
11:13:56 4007 8192000000 9912087424 10256629760 953189392 10.422 100 89 176 1 1027637056 55056608 19391056
11:15:03 2157 8192000000 9973439376 10326745088 991321552 10.794 100 88 174 0 1086960976 77362096 18277328
11:16:08 1309 8192000000 9997686736 10357862400 1019420176 11.066 100 91 180 0 1115955536 78521872 18013488
11:17:12 1455 8192000000 10010314272 10378952704 1040593744 11.270 100 89 175 0 1131037504 72613648 17830112
11:18:15 1041 8192000000 10041163152 10413531136 1061187760 11.468 100 97 177 1 1156471232 77049328 18234144
11:19:19 1240 8192000000 10043748112 10422046720 1070266624 11.555 100 91 176 0 1169186784 81353520 17566640
11:20:22 844 8192000000 10037277328 10417942528 1074280976 11.593 100 93 167 0 1153423936 62393200 16749760
11:21:24 1105 8192000000 10063267952 10451300352 1079307904 11.641 100 98 184 1 1192197792 93780272 19109616
11:22:27 1021 8192000000 10038066080 10422706176 1079804240 11.646 100 92 172 3 1159056352 61072976 18179136
11:23:29 1461 8192000000 10051672416 10439143424 1087886416 11.723 100 83 159 2 1172882432 68597936 16398080
11:24:33 1169 8192000000 10060256240 10453499904 1093398848 11.775 100 90 174 0 1184877408 73734864 17743696
11:25:35 1075 8192000000 10077246912 10470432768 1093201424 11.773 100 87 164 4 1200786240 91259456 16325360
11:26:37 1208 8192000000 10074910048 10462343168 1091567712 11.758 100 97 184 0 1190969408 81332096 18069600
11:27:39 1059 8192000000 10030370576 10418577408 1085293440 11.698 100 84 158 3 1152441792 51303344 15845008
11:28:41 1083 8192000000 10046049088 10434768896 1086826704 11.712 100 94 177 2 1169505728 64083136 18595888
11:29:43 1143 8192000000 10032158944 10412228608 1065731264 11.511 99 96 180 1 1154648688 69906336 19011088
11:30:46 1067 8192000000 10054325904 10442072064 1077457504 11.623 100 98 182 2 1176162784 80862640 17842640
11:31:47 1075 8192000000 10005966400 10382741504 1048570320 11.347 100 95 172 2 1129395968 62965584 17860064
11:32:50 1238 8192000000 9998219424 10373857280 1040535264 11.270 100 90 174 2 1122486512 63680240 18271008
11:33:53 2302 8192000000 10007311072 10400583680 1055608896 11.414 100 77 146 1 1132824064 61679584 15535584
11:34:57 1105 8192000000 9993974432 10369343488 1039362864 11.259 100 94 182 2 1115070112 57512112 18195136
11:35:59 1078 8192000000 10006206928 10389798912 1060892896 11.465 100 97 187 0 1131067376 50927312 19247168
11:37:01 1269 8192000000 10015203744 10390794240 1053099584 11.390 100 95 182 0 1139048688 67031088 18918016
11:38:05 3163 8192000000 10028109936 10404667392 1066280016 11.517 100 79 142 0 1143218288 64177152 12761120
11:39:13 12538 8192000000 10039479904 10424709120 1074250992 11.593 100 66 121 1 1165600864 79010080 12339792
11:40:31 7196 8192000000 10049573280 10443579392 1089418304 11.737 100 55 109 0 1169689008 69246288 11024416
11:41:44 1928 8192000000 10111937472 10502406144 1112742208 11.958 100 77 144 0 1223972272 95797792 15432272
11:42:49 2040 8192000000 10137817040 10529722368 1121806016 12.044 100 80 149 3 1256786896 120623888 14356992
11:43:55 4481 8192000000 10197949632 10588807168 1133270000 12.152 100 83 153 3 1311569600 161942512 16357088
11:45:12 2295 8192000000 10223107344 10629447680 1138123744 12.198 100 79 156 2 1337874976 182981776 16769456
11:46:16 1137 8192000000 10251400832 10647801856 1156955568 12.375 100 95 184 1 1363406640 184454352 21996720
11:47:19 942 8192000000 10280628080 10680434688 1151532224 12.324 100 94 181 2 1395257104 223280672 20444208
11:48:24 792 8192000000 10300249712 10708205568 1165134256 12.451 100 98 192 2 1412649280 226634880 20880144
11:49:27 1574 8192000000 10323145856 10723610624 1173067408 12.525 100 98 189 2 1431315952 238976080 19272464
11:50:34 1232 8192000000 10359482992 10766893056 1174466336 12.539 100 97 192 0 1465608672 268802768 22339568
11:51:37 1447 8192000000 10379866352 10782416896 1175929008 12.552 100 88 170 1 1491494864 296763728 18802128
11:52:42 3226 8192000000 10402047632 10808410112 1175578400 12.549 100 79 154 0 1514464656 320498800 18387456
11:53:49 882 8192000000 10436508128 10865758208 1182899648 12.617 100 97 181 0 1542177520 336513792 22764080
11:54:54 972 8192000000 10458017232 10883887104 1192283488 12.705 100 88 168 0 1562813152 349995328 20534336
11:55:59 836 8192000000 10480497376 10882621440 1174695664 12.541 99 98 193 1 1587524240 390646512 22182064
11:57:04 1765 8192000000 10493361408 10947428352 1181072288 12.600 100 97 190 1 1592676720 388813504 22790928
11:58:09 2021 8192000000 10500508000 10972446720 1181011600 12.600 100 90 177 2 1610423584 408507104 20904880
11:59:14 848 8192000000 10475979824 10940416000 1187095600 12.656 100 95 187 0 1581013104 371963680 21953824
12:00:17 20712 8192000000 10388517808 10880598016 1157482800 12.380 98 38 76 0 1520439408 352982592 9974016
12:01:41 11269 8192000000 10396554544 10830815232 1156001776 12.366 100 46 83 0 1499173088 332793664 10377648
12:02:56 15144 8192000000 10379752608 10864631808 1148026848 12.291 99 31 60 0 1499710528 343527776 8155904
12:04:24 25087 8192000000 10357491152 10847232000 1141143280 12.226 99 47 92 0 1459582784 307604720 10834784
12:05:56 40175 8192000000 10265585376 10734612480 1138160384 12.198 99 34 63 0 1451147104 304654656 8332064
12:07:39 34583 8192000000 10262113120 10730655744 1139525168 12.211 98 47 95 0 1347608896 196927632 11156096
12:09:18 26864 8192000000 10279282288 10715619328 1134118320 12.160 98 37 74 0 1346802224 204355600 8328304
12:10:51 25220 8192000000 10243020784 10762498048 1165695664 12.457 100 41 75 0 1376961008 201709616 9555728
12:12:20 27915 8192000000 10280643376 10714632192 1159338048 12.397 99 54 105 0 1361225456 189727776 12159632
12:14:15 48421 8192000000 10252166160 10768596992 1144674816 12.259 100 12 24 0 1359156304 211359824 3121664
12:16:04 30466 8192000000 10166106944 10593280000 1127292688 12.096 99 48 89 1 1241107408 103545584 10269136
12:17:42 108898 8192000000 10078704128 10571755520 1100652640 11.844 97 22 44 2 1169227504 63870000 4704864
12:20:34 55925 8192000000 10117955584 10558783488 1090507808 11.747 96 33 64 0 1158287744 61234528 6545408
12:23:09 87929 8192000000 10095026896 10568577024 1083828640 11.684 98 11 22 0 1161561088 75632576 2099872
12:25:37 55709 8192000000 10088919920 10523693056 1107553056 11.909 99 16 32 1 1151298640 40593872 3151712
12:27:33 91341 8192000000 10150516368 10610110464 1122423216 12.050 100 37 72 1 1237322928 106599952 8299760
12:31:01 148443 8192000000 10056914560 10502529024 1100851968 11.846 98 33 66 0 1154179680 46793680 6534032
12:34:30 27974 8192000000 10114697200 10555244544 1128763104 12.110 100 48 94 2 1199881104 60385504 10732496
12:35:59 45252 8192000000 10060672464 10553520128 1116481520 11.994 99 10 21 0 1169304800 50094048 2729232
12:37:45 55028 8192000000 10088972112 10543841280 1102669360 11.863 97 18 33 0 1145999744 40251952 3078432
12:39:41 36801 8192000000 10072576176 10526724096 1127570880 12.098 99 13 25 0 1166300288 35918144 2811264
12:41:18 51549 8192000000 10076332912 10527850496 1120840352 12.035 98 20 38 2 1175819408 51373568 3605488
12:43:18 47608 8192000000 10079160288 10538520576 1143178560 12.245 100 8 16 0 1176199872 31563840 1457472
12:45:06 46845 8192000000 10066487808 10524200960 1100699328 11.844 96 19 36 1 1124077392 19862928 3515136
12:46:59 16335 8192000000 10109051456 10554892288 1147785408 12.289 100 36 70 0 1205805008 50435776 7583824
12:48:17 77406 8192000000 10125670832 10597769216 1128232096 12.105 98 21 42 0 1211209728 78520416 4457216
12:50:38 34070 8192000000 10111235728 10570366976 1137169872 12.189 99 25 50 0 1185704752 42982800 5552080
12:52:30 43256 8192000000 10161447040 10603122688 1145300416 12.265 99 27 55 0 1252540752 101177824 6062512
12:54:14 55691 8192000000 10100356448 10567704576 1149884304 12.308 100 21 42 0 1218056448 63990400 4181744
12:56:09 30059 8192000000 10054182208 10491891712 1084621824 11.691 95 24 44 0 1121997152 32612464 4762864
12:57:40 124309 8192000000 10046522288 10501857280 1082870256 11.675 96 30 60 0 1130126592 41239472 6016864
13:00:46 14249 8192000000 10070568688 10518413312 1115719552 11.987 100 32 60 0 1174682560 52766992 6196016
13:02:00 24737 8192000000 10033008080 10491789312 1084570432 11.691 97 19 38 0 1124595760 36236816 3788512
13:03:32 68036 8192000000 10075325040 10524401664 1125306784 12.077 100 13 26 0 1183178784 55103568 2768432
13:06:01 46598 8192000000 10052329760 10566459392 1108472320 11.918 99 9 15 0 1162688096 52226464 1989312
13:07:48 38149 8192000000 10074596960 10506665984 1087266464 11.717 96 26 49 0 1146406704 54056176 5084064
13:09:27 22974 8192000000 10133801536 10562981888 1105037376 11.885 97 36 72 0 1208725696 95953424 7734896
13:10:53 12102 8192000000 10222985216 10650841088 1148450736 12.295 100 53 106 1 1309915296 150006640 11457920
13:12:06 23237 8192000000 10210130832 10647617536 1133165024 12.151 99 43 86 1 1306366640 163697920 9503696
13:13:29 45972 8192000000 10177410384 10659028992 1120662032 12.033 99 21 42 0 1300256976 174714096 4880848
13:15:19 100979 8192000000 10055971216 10513403904 1087242240 11.716 97 20 40 1 1141863216 49913040 4707936
13:18:08 5846 8192000000 10123079472 10541666304 1121537072 12.042 100 54 108 0 1200248400 67982576 10728752
13:19:15 21967 8192000000 10139259328 10590040064 1116652240 11.995 99 44 89 0 1229426704 103822848 8951616
13:21:08 96884 8192000000 10083906128 10524659712 1130215440 12.123 100 28 56 0 1160952480 25172192 5564848
13:23:45 58623 8192000000 10046271008 10510340096 1103909440 11.875 98 11 22 0 1144075568 37955536 2210592
13:25:46 60617 8192000000 10061564288 10508886016 1119927984 12.026 99 18 36 0 1146373856 22784688 3661184
13:27:50 80635 8192000000 10083144112 10545373184 1116714304 11.996 98 11 22 0 1181437872 62365968 2357600
13:30:49 59343 8192000000 10098658288 10547847168 1118945696 12.017 98 19 38 1 1161469184 38250128 4273360
13:32:59 34205 8192000000 10144993680 10616410112 1149416128 12.304 100 41 82 0 1232624816 74306608 8902080
13:34:43 34919 8192000000 10127517520 10582200320 1139009280 12.206 99 25 49 0 1219383760 74809600 5564880
13:36:25 26703 8192000000 10109630336 10561863680 1136791856 12.185 100 36 72 0 1201377872 56808272 7777744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-02-2023 13:38:36 to 11-02-2023 13:38:52.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2023 13:38:53 to 11-02-2023 13:38:55.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221849
MON$OLDEST_ACTIVE               226265
MON$OLDEST_SNAPSHOT             226265
MON$NEXT_TRANSACTION            226265
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-30 07:30:08.6030 GMT
MON$PAGES                       2088416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

11.02.23 13:38:57. There are 3 logs in /var/tmp/logs-oltp40 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-02-11 13:38:59 No severe PSQL-related problems occured
11.02.23 13:39:01. End of report.