Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2023 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15238580 kB
MemAvailable 30411504 kB
Buffers 0 kB
Cached 16458176 kB
SwapCached 1648 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-21 19:38:32
DTS_END                         2023-01-21 19:38:32.6120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41252
TRN_ID                          250399
ATT_ID                          738
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: 9398.78 20016 1127854
customer order: creation 186.03 351 22323
customer order: refuse 15.78 313 1894
order to supplier: creation 48.39 1228 5807
order to supplier: removal 3.42 101570 410
invoice (draft): creation 49.39 3149 5927
invoice (draft): removal 51.86 750 6223
invoice accept: apply 43.65 16432 5238
invoice accept: cancel 47.73 856 5727
customer reserve: creation 3989.28 163 478713
customer reserve: removal 4650.38 59 558045
realization accept: apply 89.40 189 10728
realization accept: cancel 83.33 116 10000
payment from customer: creation 63.70 113 7644
payment from customer: removal 5.98 68 717
payment to supplier: creation 53.76 153 6451
payment to supplier: removal 3.81 65 457
service: total inventory turnovers 4.90 1216 588
service: total monetary turnovers 7.19 21 863
service: aggregate perf. data 0.79 26326 95
service: refresh index statistics 0.03 247177 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2023 19:39:36 to 21-01-2023 19:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6743
TEST_TIME 2 7141
TEST_TIME 3 6376
TEST_TIME 4 5468
TEST_TIME 5 5393
TEST_TIME 6 5189
TEST_TIME 7 5126
TEST_TIME 8 5283
TEST_TIME 9 5497
TEST_TIME 10 5394
TEST_TIME 11 5794
TEST_TIME 12 6158
TEST_TIME 13 6567
TEST_TIME 14 6865
TEST_TIME 15 7540
TEST_TIME 16 7666
TEST_TIME 17 7627
TEST_TIME 18 7522
TEST_TIME 19 7753
TEST_TIME 20 8098
TEST_TIME 21 8131
TEST_TIME 22 9160
TEST_TIME 23 9574
TEST_TIME 24 9539
TEST_TIME 25 9893
TEST_TIME 26 9690
TEST_TIME 27 10168
TEST_TIME 28 11245
TEST_TIME 29 11225
TEST_TIME 30 11126
TEST_TIME 31 10932
TEST_TIME 32 10776
TEST_TIME 33 10725
TEST_TIME 34 10689
TEST_TIME 35 10495
TEST_TIME 36 10542
TEST_TIME 37 10425
TEST_TIME 38 10341
TEST_TIME 39 10205
TEST_TIME 40 10206
TEST_TIME 41 10105
TEST_TIME 42 10078
TEST_TIME 43 10143
TEST_TIME 44 10002
TEST_TIME 45 9866
TEST_TIME 46 9804
TEST_TIME 47 9723
TEST_TIME 48 9812
TEST_TIME 49 9784
TEST_TIME 50 9798
TEST_TIME 51 9813
TEST_TIME 52 9863
TEST_TIME 53 9881
TEST_TIME 54 9888
TEST_TIME 55 9873
TEST_TIME 56 9831
TEST_TIME 57 9961
TEST_TIME 58 9927
TEST_TIME 59 9999
TEST_TIME 60 9933
TEST_TIME 61 9820
TEST_TIME 62 9805
TEST_TIME 63 9730
TEST_TIME 64 9718
TEST_TIME 65 9638
TEST_TIME 66 9607
TEST_TIME 67 9534
TEST_TIME 68 9464
TEST_TIME 69 9506
TEST_TIME 70 9441
TEST_TIME 71 9458
TEST_TIME 72 9493
TEST_TIME 73 9441
TEST_TIME 74 9456
TEST_TIME 75 9549
TEST_TIME 76 9553
TEST_TIME 77 9601
TEST_TIME 78 9566
TEST_TIME 79 9560
TEST_TIME 80 9688
TEST_TIME 81 9674
TEST_TIME 82 9717
TEST_TIME 83 9727
TEST_TIME 84 9687
TEST_TIME 85 9634
TEST_TIME 86 9595
TEST_TIME 87 9594
TEST_TIME 88 9556
TEST_TIME 89 9505
TEST_TIME 90 9469
TEST_TIME 91 9385
TEST_TIME 92 9381
TEST_TIME 93 9472
TEST_TIME 94 9471
TEST_TIME 95 9494
TEST_TIME 96 9501
TEST_TIME 97 9467
TEST_TIME 98 9519
TEST_TIME 99 9492
TEST_TIME 100 9500
TEST_TIME 101 9600
TEST_TIME 102 9632
TEST_TIME 103 9629
TEST_TIME 104 9640
TEST_TIME 105 9674
TEST_TIME 106 9689
TEST_TIME 107 9608
TEST_TIME 108 9565
TEST_TIME 109 9536
TEST_TIME 110 9517
TEST_TIME 111 9493
TEST_TIME 112 9444
TEST_TIME 113 9508
TEST_TIME 114 9506
TEST_TIME 115 9457
TEST_TIME 116 9417
TEST_TIME 117 9461
TEST_TIME 118 9429
TEST_TIME 119 9450
TEST_TIME 120 9403

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-01-2023 19:39:39 to 21-01-2023 19:39:43.

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 1573249 1573249 0 0.00 0 18474 6 0 0
doc_list_biud 1573249 1573249 0 0.00 0 27505 26 0 0
sp_add_invoice_to_stock 5404 5238 166 3.07 43 259007 16433 0 166
sp_cancel_adding_invoice 5727 5727 0 0.00 4 49897 857 0 0
sp_cancel_client_order 1894 1894 0 0.00 4 7784 313 0 0
sp_cancel_customer_reserve 558045 558045 0 0.00 0 22719 59 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 2 4160 68 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 3 6440 65 0 0
sp_cancel_supplier_invoice 6239 6223 16 0.25 1 27522 751 0 16
sp_cancel_supplier_order 410 410 0 0.00 7 1110212 101570 0 0
sp_cancel_write_off 10000 10000 0 0.00 1 14929 117 0 0
sp_client_order 22323 22323 0 0.00 3 36563 352 0 0
sp_customer_reserve 478713 478713 0 0.00 0 40414 164 0 0
sp_fill_shopping_cart 34051 34051 0 0.00 1 9365 35 0 0
sp_fill_shopping_cart_clo_res 478711 478711 0 0.00 0 11632 12 0 0
sp_get_clo_for_invoice 5227 5227 0 0.00 0 31267 308 0 0
sp_kill_cost_storno 16901 16901 0 0.00 0 13031 56 0 0
sp_kill_qstorno_ret_qs2qd 1132723 1132723 0 0.00 0 27494 32 0 0
sp_kill_qty_storno 598252 598252 0 0.00 0 27501 69 0 0
sp_lock_selected_doc 22112 22112 0 0.00 0 7810 7 0 0
sp_make_cost_storno 30048 30048 0 0.00 0 16537 54 0 0
sp_multiply_rows_for_pdistr 30048 30048 0 0.00 0 8155 27 0 0
sp_multiply_rows_for_qdistr 22323 22323 0 0.00 0 19475 154 0 0
sp_pay_from_customer 7644 7644 0 0.00 4 26271 114 0 0
sp_pay_to_supplier 6451 6451 0 0.00 4 24828 154 0 0
sp_payment_common 14095 14095 0 0.00 1 26267 30 0 0
sp_qd_handle_on_cancel_clo 1894 1894 0 0.00 0 7774 276 0 0
sp_qd_handle_on_invoice_upd_sts 10952 10952 0 0.00 0 23539 308 0 0
sp_qd_handle_on_reserve_upd_sts 10728 10728 0 0.00 0 15207 68 0 0
sp_reserve_write_off 10728 10728 0 0.00 6 18499 189 0 0
sp_supplier_invoice 5927 5927 0 0.00 5 97486 3149 0 0
sp_supplier_order 5807 5807 0 0.00 4 30402 1228 0 0
srv_aggregate_perf_data 96 95 1 1.04 2547 680703 26327 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1723 132999 43667 0 0
srv_find_qd_qs_mism 1078120 1078120 0 0.00 0 14268 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 34 149877 1217 0 0
srv_make_money_saldo 863 863 0 0.00 0 448 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 167821 345663 247177 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5238 5238 0 0.00 0 19099 173 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 4025 125 0 0
t$perf-norm:sp_cancel_client_order 1894 1894 0 0.00 0 3623 17 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 2810 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 1691 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 389 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 1627 19 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 22956 1509 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 3741 9 0 0
t$perf-norm:sp_client_order 22323 22323 0 0.00 0 16791 19 0 0
t$perf-norm:sp_customer_reserve 1132 1132 0 0.00 0 3372 17 0 0
t$perf-norm:sp_pay_from_customer 7644 7644 0 0.00 0 13803 16 0 0
t$perf-norm:sp_pay_to_supplier 6451 6451 0 0.00 0 17478 14 0 0
t$perf-norm:sp_reserve_write_off 10728 10728 0 0.00 0 10198 12 0 0
t$perf-norm:sp_supplier_invoice 5927 5927 0 0.00 0 10709 19 0 0
t$perf-norm:sp_supplier_order 5807 5807 0 0.00 0 5039 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 31 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 285 8 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 2601 24 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 1295 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10782 10450 332 3.07 0 5851 20 0 332
v_all_customers 40272 40272 0 0.00 0 18 1 0 0
v_all_suppliers 32788 32788 0 0.00 0 23 1 0 0
v_all_wares 44646 44646 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 5068 12 0 0
v_cancel_client_order 3788 3788 0 0.00 0 6612 9 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 3143 7 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 1054 5 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 2679 9 0 0
v_cancel_supplier_invoice 932 900 32 3.43 0 5280 25 0 32
v_cancel_supplier_order 820 820 0 0.00 0 5870 28 0 0
v_cancel_write_off 2142 2142 0 0.00 0 4012 15 0 0
v_max_id_clo_ord 5807 5807 0 0.00 0 394 1 0 0
v_max_id_clo_res 1132 1132 0 0.00 0 6305 17 0 0
v_max_id_ord_sup 5921 5921 0 0.00 0 3797 5 0 0
v_max_non_paid_invoice 1785 1785 0 0.00 0 2212 4 0 0
v_max_non_paid_realizn 4141 4141 0 0.00 0 4389 4 0 0
v_min_id_clo_ord 5807 5807 0 0.00 0 1873 6 0 0
v_min_id_clo_res 1132 1132 0 0.00 0 3784 12 0 0
v_min_id_ord_sup 5921 5921 0 0.00 0 3251 6 0 0
v_min_non_paid_invoice 6451 6451 0 0.00 0 13225 25 0 0
v_min_non_paid_realizn 7644 7644 0 0.00 0 9790 20 0 0
v_our_firm 11380 11380 0 0.00 0 21 1 0 0
v_reserve_write_off 21456 21456 0 0.00 0 6093 9 0 0
x_lock_depdocs_on_canc_invoice 11500 11500 0 0.00 0 23495 132 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 8616 377 0 0
x_make_qstorno_1000_1200 5807 5807 0 0.00 1 30396 1161 0 0
x_make_qstorno_1200_2000 5927 5927 0 0.00 2 97465 3015 0 0
x_make_qstorno_2100_3300 478713 478713 0 0.00 0 37500 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2023 19:39:44 to 21-01-2023 19:39:48.

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:36 9 8192000000 8807350256 8821514240 11746080 0.143 3 3 4 0 11929984 76800 107104
17:09:38 13154 8192000000 9895240880 10202824704 984706464 10.730 100 87 174 0 1013222016 5881664 22633888
17:10:55 11834 8192000000 9935894368 10252472320 1012303568 10.998 100 86 172 0 1042930112 7812496 22814048
17:12:13 19225 8192000000 9966054528 10297696256 1048074160 11.342 100 81 160 1 1077635088 9412336 20148592
17:13:39 8741 8192000000 10022436160 10365337600 1108044080 11.914 100 85 171 0 1139314112 10855456 20414576
17:14:52 6199 8192000000 10074202368 10432110592 1162517680 12.427 100 75 150 0 1192139952 12045856 17576416
17:16:00 4607 8192000000 10115404080 10484498432 1208720720 12.857 100 77 154 2 1238652352 12328080 17603552
17:17:06 3865 8192000000 10154424592 10531966976 1242740752 13.171 100 79 158 1 1273092240 12795712 17555776
17:18:11 1462 8192000000 10177878576 10561589248 1274560720 13.463 100 82 162 1 1306043552 13464400 18018432
17:19:14 800 8192000000 10219425024 10601181184 1303948320 13.731 100 96 192 1 1338288944 13544336 20796288
17:20:17 30151 8192000000 10186897008 10600529920 1302847536 13.721 100 24 48 1 1314331520 6175120 5308864
17:21:49 2756 8192000000 10204659696 10611593216 1312462592 13.808 100 34 69 0 1327162400 7114320 7585488
17:22:56 9894 8192000000 10201921760 10619981824 1320570608 13.882 100 16 32 0 1330037168 5730768 3735792
17:24:07 17183 8192000000 10232623696 10641592320 1330160352 13.969 100 39 78 1 1345745040 6654368 8930320
17:25:25 19669 8192000000 10245479232 10648891392 1332457056 13.989 100 58 113 1 1352685024 7727296 12500672
17:26:45 6610 8192000000 10232153680 10648502272 1337114160 14.031 100 35 70 1 1351945200 7166400 7664640
17:27:54 3518 8192000000 10223878336 10638987264 1327473712 13.944 100 26 50 1 1340171856 6977200 5720944
17:29:00 16387 8192000000 10195258288 10600550400 1259061712 13.321 96 31 60 0 1271330960 5057440 7211808
17:30:17 897 8192000000 10200768640 10597773312 1293415344 13.635 100 59 119 0 1313427376 5604400 14407632
17:31:18 5451 8192000000 10141802400 10543284224 1233781952 13.089 98 34 66 0 1248266656 6721072 7763632
17:32:24 7103 8192000000 10141792480 10535424000 1245467984 13.197 99 28 57 1 1256748960 4790800 6490176
17:33:32 42529 8192000000 10157650624 10566217728 1270241184 13.424 100 35 71 0 1284678080 6527600 7909296
17:35:19 7247 8192000000 10199322272 10608906240 1299529008 13.691 100 33 66 0 1313460976 6333424 7598544
17:36:28 6522 8192000000 10197687440 10613911552 1312123584 13.805 100 24 48 1 1323343184 5514496 5705104
17:37:36 64495 8192000000 10227083312 10642128896 1326490256 13.935 100 36 73 1 1340268832 5199440 8579136
17:39:50 27351 8192000000 10263454624 10677112832 1358328576 14.222 100 41 80 1 1375446832 8436048 8682208
17:41:23 10513 8192000000 10267827328 10692321280 1373370944 14.357 100 48 96 1 1392957344 9104464 10481936
17:42:46 20515 8192000000 10287371616 10707865600 1377217600 14.392 99 41 82 1 1398657984 12241024 9199360
17:44:12 21352 8192000000 10314369856 10742202368 1402633856 14.618 100 40 80 1 1428955168 16361808 9959504
17:45:39 11899 8192000000 10333479776 10758701056 1416497056 14.742 100 50 98 1 1447411248 19348848 11565344
17:47:06 5650 8192000000 10341666560 10771582976 1420202624 14.774 100 40 79 0 1453312544 22661744 10448176
17:48:16 1723 8192000000 10344684000 10766700544 1405473200 14.644 99 63 126 3 1447348768 27076976 14798592
17:49:23 8905 8192000000 10344626608 10763169792 1404217984 14.633 100 66 133 0 1450651120 30348000 16085136
17:50:48 2114 8192000000 10355815872 10773782528 1404763584 14.637 99 80 159 0 1455336112 31283360 19289168
17:51:56 8158 8192000000 10352129440 10775089152 1407597424 14.663 100 61 122 2 1459444336 36587824 15259088
17:53:09 4570 8192000000 10325934768 10734915584 1370896512 14.335 99 68 133 2 1425967040 38734784 16335744
17:54:18 8588 8192000000 10305575952 10723336192 1348654128 14.135 97 57 115 0 1399818704 37035280 14129296
17:55:39 1866 8192000000 10336512208 10741583872 1361093024 14.247 98 78 155 0 1424115792 44339408 18683360
17:56:48 2909 8192000000 10338534768 10743222272 1370637808 14.333 100 83 162 0 1443420048 51384432 21397808
17:57:58 4091 8192000000 10354050848 10766000128 1387638464 14.485 100 74 144 2 1463504752 56298048 19568240
17:59:07 2133 8192000000 10366909408 10778152960 1394621552 14.547 100 72 142 1 1468314336 54860144 18832640
18:00:15 9290 8192000000 10328975376 10749767680 1359052496 14.229 99 48 95 2 1435982672 63902784 13027392
18:01:26 21209 8192000000 10313152256 10749562880 1372762192 14.352 100 28 52 2 1419813072 39389024 7661856
18:02:50 23584 8192000000 10323797536 10764779520 1382969824 14.443 100 31 62 1 1431832576 40398752 8464000
18:04:15 18996 8192000000 10312249472 10750828544 1371421776 14.340 99 22 44 0 1415264128 38207216 5635136
18:05:39 24145 8192000000 10314061456 10754920448 1368355664 14.312 100 17 34 0 1418281344 45230288 4695392
18:07:04 57794 8192000000 10277783680 10727895040 1340611456 14.063 98 8 17 0 1365537920 23105328 1821136
18:09:06 64268 8192000000 10301127216 10738679808 1359142608 14.230 98 24 48 0 1380718064 16409072 5166384
18:11:14 28772 8192000000 10314809408 10738585600 1361991296 14.255 99 33 66 0 1385721008 15826816 7902896
18:12:46 106139 8192000000 10303770928 10746040320 1382278784 14.437 100 30 57 0 1400982112 11629584 7073744
18:15:43 23379 8192000000 10310596240 10750042112 1378916512 14.407 99 49 96 3 1398709136 8021024 11771600
18:17:07 26508 8192000000 10306847696 10744733696 1386326784 14.473 100 53 103 1 1406436320 8550144 11559392
18:18:42 47005 8192000000 10299021408 10752204800 1368422800 14.313 98 17 34 0 1380666752 7885760 4358192
18:20:32 24051 8192000000 10307917520 10760159232 1395985856 14.559 100 36 70 0 1417558080 13522352 8049872
18:22:00 54252 8192000000 10304965744 10762657792 1398895760 14.585 100 9 18 0 1411135824 9776416 2463648
18:23:54 73866 8192000000 10305251360 10765373440 1395429264 14.554 99 15 30 0 1409784832 10707952 3647616
18:27:17 58790 8192000000 10292763536 10761138176 1397368560 14.572 100 14 26 1 1410048832 9771280 2908992
18:29:40 71623 8192000000 10307283808 10775556096 1363931808 14.273 97 15 30 0 1381529888 13935216 3662864
18:31:52 26243 8192000000 10270296176 10717409280 1314933312 13.831 94 24 49 0 1329306528 9124320 5248896
18:33:19 47532 8192000000 10280828784 10733129728 1350223232 14.149 99 31 62 0 1367274768 10476416 6575120
18:35:31 49329 8192000000 10278643616 10718855168 1310273200 13.789 95 14 29 0 1322394912 9289296 2832416
18:37:22 132999 8192000000 10263681888 10708541440 1252580624 13.262 91 20 40 0 1269354736 11977328 4796784
18:40:41 97345 8192000000 10260593040 10709020672 1292113392 13.623 94 30 59 0 1307640416 9514768 6012256
18:43:21 61880 8192000000 10249668064 10703126528 1318168080 13.860 97 8 16 0 1331324336 11449664 1706592
18:45:46 68090 8192000000 10273696448 10725670912 1363530768 14.269 100 16 32 0 1379331568 11795856 4004944
18:48:08 29128 8192000000 10260036656 10729725952 1350067120 14.148 99 6 12 0 1365912160 15068416 776624
18:49:40 69324 8192000000 10276280480 10742534144 1351606992 14.162 98 13 25 0 1367265136 12976880 2681264
18:51:50 20334 8192000000 10312843440 10753818624 1353295696 14.177 97 51 102 0 1376097296 11167872 11633728
18:53:20 49215 8192000000 10285408368 10746081280 1378429088 14.403 100 20 40 0 1397947728 14732096 4786544
18:55:11 47522 8192000000 10275682128 10739916800 1324081376 13.914 96 29 58 0 1351246784 20638784 6526624
18:57:25 49981 8192000000 10299574544 10754572288 1372918384 14.353 99 22 44 0 1392307296 14391664 4997248
18:59:28 58744 8192000000 10301601968 10763616256 1354990528 14.192 98 16 32 0 1374558336 15893568 3674240
19:01:28 115042 8192000000 10292761360 10742943744 1382266128 14.437 100 20 40 0 1401368048 14396448 4705472
19:04:24 11476 8192000000 10291536464 10749202432 1385829856 14.469 100 33 65 0 1405457408 13327952 6299600
19:05:37 46349 8192000000 10287388032 10752446464 1384178192 14.454 99 19 38 2 1396484880 7644528 4662160
19:07:23 80719 8192000000 10306056640 10764644352 1373028256 14.354 97 20 40 1 1387813392 10070784 4714352
19:10:03 52378 8192000000 10304283328 10764058624 1372899760 14.353 98 29 56 1 1399345424 19348992 7096672
19:12:11 48760 8192000000 10299681888 10763218944 1393202272 14.534 99 26 49 0 1406445728 8048016 5195440
19:14:07 60761 8192000000 10302716736 10763046912 1324365120 13.916 94 12 24 0 1334790624 8195296 2230208
19:16:09 51783 8192000000 10314763680 10775990272 1403447552 14.626 100 34 68 0 1428370064 16214240 8708272
19:18:01 88197 8192000000 10289446160 10770104320 1344150368 14.095 96 16 32 1 1370201040 22177552 3873120
19:20:33 81750 8192000000 10272342976 10716803072 1267578368 13.399 92 19 39 0 1280780576 9056784 4145424
19:23:02 53512 8192000000 10280384144 10738081792 1340386640 14.061 98 16 33 0 1355174752 11360496 3427616
19:24:59 117793 8192000000 10265212160 10713182208 1326844720 13.939 97 21 42 1 1341126464 9291440 4990304
19:27:59 49366 8192000000 10267102912 10723868672 1349887648 14.146 99 10 20 0 1363627536 10913584 2826304
19:29:49 80107 8192000000 10255177776 10707890176 1300600416 13.701 95 14 29 0 1321972656 17995744 3376496
19:32:09 53458 8192000000 10214179984 10661056512 1286132816 13.569 95 12 24 0 1302113824 12824560 3156448
19:34:05 73151 8192000000 10255247888 10716065792 1340523984 14.062 99 12 24 0 1356111040 12566848 3020208
19:36:21 46392 8192000000 10271382464 10719580160 1356456736 14.206 100 35 68 0 1372540432 8128672 7955024
19:38:12 22814 8192000000 9183427008 9440645120 344034336 4.030 26 14 28 0 349053904 2209360 2810208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-01-2023 19:39:50 to 21-01-2023 19:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 166 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2023 19:40:06 to 21-01-2023 19:40:08.

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          250707
MON$OLDEST_ACTIVE               250708
MON$OLDEST_SNAPSHOT             250708
MON$NEXT_TRANSACTION            250708
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                       2038304
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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'

21.01.23 19:40:10. 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-21 19:40:12 No severe PSQL-related problems occured
21.01.23 19:40:13. End of report.