Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.2021 19: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 16821652 kB
MemAvailable 31622764 kB
Buffers 0 kB
Cached 14862224 kB
SwapCached 0 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.8
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 2021-11-13 19:37:54
DTS_END                         2021-11-13 19:37:54.7560
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/45138
TRN_ID                          248394
ATT_ID                          695
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: 9221.51 20766 1106581
customer order: creation 180.06 353 21607
customer order: refuse 14.90 353 1788
order to supplier: creation 48.05 1358 5766
order to supplier: removal 3.24 86405 389
invoice (draft): creation 47.73 3275 5728
invoice (draft): removal 52.18 722 6261
invoice accept: apply 43.20 17269 5184
invoice accept: cancel 47.10 840 5652
customer reserve: creation 3861.64 176 463397
customer reserve: removal 4612.48 47 553498
realization accept: apply 85.50 191 10260
realization accept: cancel 93.09 91 11171
payment from customer: creation 60.47 130 7256
payment from customer: removal 5.70 74 684
payment to supplier: creation 50.40 163 6048
payment to supplier: removal 3.72 96 446
service: total inventory turnovers 4.54 1134 545
service: total monetary turnovers 6.60 21 792
service: aggregate perf. data 0.88 16756 106
service: refresh index statistics 0.03 285859 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-11-2021 19:39:19 to 13-11-2021 19:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1486
TEST_TIME 2 3237
TEST_TIME 3 4132
TEST_TIME 4 4228
TEST_TIME 5 4268
TEST_TIME 6 4211
TEST_TIME 7 4378
TEST_TIME 8 4641
TEST_TIME 9 4826
TEST_TIME 10 4911
TEST_TIME 11 4831
TEST_TIME 12 5058
TEST_TIME 13 5802
TEST_TIME 14 5941
TEST_TIME 15 6343
TEST_TIME 16 6743
TEST_TIME 17 6886
TEST_TIME 18 7382
TEST_TIME 19 8063
TEST_TIME 20 8101
TEST_TIME 21 8664
TEST_TIME 22 8994
TEST_TIME 23 9304
TEST_TIME 24 9369
TEST_TIME 25 9652
TEST_TIME 26 9757
TEST_TIME 27 9663
TEST_TIME 28 9448
TEST_TIME 29 9796
TEST_TIME 30 9848
TEST_TIME 31 9795
TEST_TIME 32 9912
TEST_TIME 33 9935
TEST_TIME 34 9787
TEST_TIME 35 9574
TEST_TIME 36 9339
TEST_TIME 37 9505
TEST_TIME 38 9437
TEST_TIME 39 9531
TEST_TIME 40 9414
TEST_TIME 41 9384
TEST_TIME 42 9359
TEST_TIME 43 9388
TEST_TIME 44 9281
TEST_TIME 45 9310
TEST_TIME 46 9272
TEST_TIME 47 9231
TEST_TIME 48 9161
TEST_TIME 49 9198
TEST_TIME 50 9127
TEST_TIME 51 9114
TEST_TIME 52 9069
TEST_TIME 53 9121
TEST_TIME 54 9164
TEST_TIME 55 9213
TEST_TIME 56 9226
TEST_TIME 57 9171
TEST_TIME 58 9276
TEST_TIME 59 9345
TEST_TIME 60 9416
TEST_TIME 61 9492
TEST_TIME 62 9630
TEST_TIME 63 9645
TEST_TIME 64 9687
TEST_TIME 65 9655
TEST_TIME 66 9586
TEST_TIME 67 9561
TEST_TIME 68 9529
TEST_TIME 69 9483
TEST_TIME 70 9436
TEST_TIME 71 9433
TEST_TIME 72 9382
TEST_TIME 73 9354
TEST_TIME 74 9306
TEST_TIME 75 9225
TEST_TIME 76 9377
TEST_TIME 77 9370
TEST_TIME 78 9384
TEST_TIME 79 9382
TEST_TIME 80 9442
TEST_TIME 81 9487
TEST_TIME 82 9468
TEST_TIME 83 9461
TEST_TIME 84 9499
TEST_TIME 85 9553
TEST_TIME 86 9508
TEST_TIME 87 9624
TEST_TIME 88 9644
TEST_TIME 89 9552
TEST_TIME 90 9515
TEST_TIME 91 9465
TEST_TIME 92 9453
TEST_TIME 93 9411
TEST_TIME 94 9366
TEST_TIME 95 9320
TEST_TIME 96 9320
TEST_TIME 97 9343
TEST_TIME 98 9361
TEST_TIME 99 9392
TEST_TIME 100 9368
TEST_TIME 101 9368
TEST_TIME 102 9429
TEST_TIME 103 9457
TEST_TIME 104 9419
TEST_TIME 105 9449
TEST_TIME 106 9421
TEST_TIME 107 9425
TEST_TIME 108 9429
TEST_TIME 109 9438
TEST_TIME 110 9362
TEST_TIME 111 9340
TEST_TIME 112 9279
TEST_TIME 113 9281
TEST_TIME 114 9318
TEST_TIME 115 9316
TEST_TIME 116 9273
TEST_TIME 117 9268
TEST_TIME 118 9257
TEST_TIME 119 9227
TEST_TIME 120 9224

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-11-2021 19:39:23 to 13-11-2021 19:39:26.

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 1538406 1538406 0 0.00 0 23036 6 0 0
doc_list_biud 1538405 1538405 0 0.00 0 40244 22 0 0
sp_add_invoice_to_stock 5280 5184 96 1.81 46 230412 17270 0 96
sp_cancel_adding_invoice 5652 5652 0 0.00 4 73677 840 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 16350 354 0 0
sp_cancel_customer_reserve 553498 553498 0 0.00 1 25740 47 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 3579 74 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 2 7295 97 0 0
sp_cancel_supplier_invoice 6268 6261 7 0.11 2 24545 723 0 7
sp_cancel_supplier_order 389 389 0 0.00 6 1013253 86406 0 0
sp_cancel_write_off 11171 11171 0 0.00 1 15019 91 0 0
sp_client_order 21607 21607 0 0.00 3 21653 354 0 0
sp_customer_reserve 463397 463397 0 0.00 0 126361 176 0 0
sp_fill_shopping_cart 33093 33093 0 0.00 1 10193 37 0 0
sp_fill_shopping_cart_clo_res 463394 463394 0 0.00 0 29379 13 0 0
sp_get_clo_for_invoice 5181 5181 0 0.00 0 28092 295 0 0
sp_kill_cost_storno 17953 17953 0 0.00 0 14933 50 0 0
sp_kill_qstorno_ret_qs2qd 1124817 1124817 0 0.00 0 25733 26 0 0
sp_kill_qty_storno 594200 594200 0 0.00 0 40243 57 0 0
sp_lock_selected_doc 21354 21354 0 0.00 0 12104 6 0 0
sp_make_cost_storno 28745 28745 0 0.00 0 23023 61 0 0
sp_multiply_rows_for_pdistr 28745 28745 0 0.00 0 19272 34 0 0
sp_multiply_rows_for_qdistr 21607 21607 0 0.00 0 17340 153 0 0
sp_pay_from_customer 7256 7256 0 0.00 4 23720 130 0 0
sp_pay_to_supplier 6048 6048 0 0.00 4 23809 163 0 0
sp_payment_common 13304 13304 0 0.00 1 20862 46 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 16343 331 0 0
sp_qd_handle_on_invoice_upd_sts 10833 10833 0 0.00 0 40240 316 0 0
sp_qd_handle_on_reserve_upd_sts 10260 10260 0 0.00 0 19174 62 0 0
sp_reserve_write_off 10260 10260 0 0.00 6 20610 192 0 0
sp_supplier_invoice 5728 5728 0 0.00 5 119724 3275 0 0
sp_supplier_order 5766 5766 0 0.00 5 46928 1358 0 0
srv_aggregate_perf_data 107 106 1 0.93 3212 114297 16757 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1237 170051 49112 0 0
srv_find_qd_qs_mism 1059090 1059090 0 0.00 0 15206 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 50 38993 1134 0 0
srv_make_money_saldo 792 792 0 0.00 1 477 21 0 0
srv_recalc_idx_stat 3 3 0 0.00 200780 387656 285860 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5184 5184 0 0.00 0 10409 151 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 0 16879 138 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 2876 14 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1684 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 437 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 1493 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 7285 40 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 33902 1624 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 1261 9 0 0
t$perf-norm:sp_client_order 21607 21607 0 0.00 0 11649 17 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 3597 7 0 0
t$perf-norm:sp_pay_from_customer 7256 7256 0 0.00 0 5543 9 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 30162 16 0 0
t$perf-norm:sp_reserve_write_off 10260 10260 0 0.00 0 19109 16 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 17491 22 0 0
t$perf-norm:sp_supplier_order 5766 5766 0 0.00 0 5230 13 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 242 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 651 11 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 4741 30 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 4716 16 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10554 10362 192 1.81 0 6658 19 0 192
v_all_customers 39208 39208 0 0.00 0 17 1 0 0
v_all_suppliers 31484 31484 0 0.00 0 20 1 0 0
v_all_wares 43214 43214 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 1103 10 0 0
v_cancel_client_order 3576 3576 0 0.00 0 2920 4 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 2989 10 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 1634 5 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 2107 7 0 0
v_cancel_supplier_invoice 818 804 14 1.71 0 2677 22 0 14
v_cancel_supplier_order 778 778 0 0.00 0 2955 7 0 0
v_cancel_write_off 2076 2076 0 0.00 0 1571 13 0 0
v_max_id_clo_ord 5765 5765 0 0.00 0 1435 2 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 3438 14 0 0
v_max_id_ord_sup 5720 5720 0 0.00 0 5440 5 0 0
v_max_non_paid_invoice 1791 1791 0 0.00 0 441 2 0 0
v_max_non_paid_realizn 3858 3858 0 0.00 0 4064 5 0 0
v_min_id_clo_ord 5765 5765 0 0.00 0 9718 10 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 1928 5 0 0
v_min_id_ord_sup 5720 5720 0 0.00 0 3522 5 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 17549 40 0 0
v_min_non_paid_realizn 7256 7256 0 0.00 0 17009 31 0 0
v_our_firm 10802 10802 0 0.00 0 19 1 0 0
v_reserve_write_off 20520 20520 0 0.00 0 4299 5 0 0
x_lock_depdocs_on_canc_invoice 11511 11511 0 0.00 0 19276 117 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 9748 390 0 0
x_make_qstorno_1000_1200 5766 5766 0 0.00 1 46253 1273 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 3 119716 3149 0 0
x_make_qstorno_2100_3300 463397 463397 0 0.00 0 126361 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-11-2021 19:39:27 to 13-11-2021 19:39:31.

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:07:58 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:59 29137 8192000000 9907178496 10220535808 983183584 10.715 100 82 164 0 1010599648 6023136 21392928
17:10:41 26266 8192000000 9965295264 10293002240 1043644512 11.300 100 93 185 1 1076112240 9025408 23442320
17:12:11 18299 8192000000 10005308976 10343100416 1083172288 11.678 100 80 160 0 1113232096 10612304 19447504
17:13:32 14777 8192000000 10045695408 10395131904 1128225536 12.105 100 85 170 1 1159767920 11489408 20052976
17:14:52 7040 8192000000 10087972624 10451542016 1179158848 12.582 100 78 154 0 1208302800 11825520 17318432
17:16:01 3548 8192000000 10140693200 10507792384 1225496240 13.012 100 85 170 1 1258431248 13823408 19111600
17:17:06 3107 8192000000 10179730816 10556579840 1260191616 13.332 100 82 162 0 1291985680 13944752 17849312
17:18:11 1829 8192000000 10217424816 10600607744 1294191520 13.642 100 94 188 0 1334215008 18231504 21791984
17:19:13 5501 8192000000 10191710016 10603876352 1307496640 13.763 100 45 90 1 1325953600 8220992 10235968
17:20:21 5187 8192000000 10200493520 10614149120 1315670208 13.837 100 24 47 3 1327962400 6375712 5916480
17:21:27 36740 8192000000 10230421792 10642186240 1325719056 13.928 100 49 97 0 1343146448 5377984 12049408
17:23:07 5806 8192000000 10224136240 10641326080 1332104832 13.986 100 34 68 1 1347035680 6866928 8063920
17:24:15 9400 8192000000 10234800768 10656759808 1336085760 14.022 100 21 42 2 1346321792 5338848 4897184
17:25:25 15860 8192000000 10229723568 10648936448 1327914512 13.948 99 27 54 2 1339810000 5575648 6319840
17:26:41 11929 8192000000 10226040784 10654175232 1339034160 14.049 100 14 29 0 1347395968 5395456 2966352
17:27:53 6769 8192000000 10218632400 10632130560 1309550896 13.782 99 24 48 0 1320288592 5020768 5716928
17:29:02 5173 8192000000 10151703456 10541998080 1213950272 12.906 97 28 56 0 1230298016 10209152 6138592
17:30:07 57317 8192000000 10145291760 10544406528 1188119184 12.666 95 32 64 2 1201243408 5479760 7644464
17:32:05 3550 8192000000 10155767648 10560868352 1265383408 13.379 100 33 65 1 1279483536 6270912 7829216
17:33:11 7310 8192000000 10194002848 10596364288 1290963936 13.613 100 20 40 0 1300801936 5229088 4608912
17:34:20 11266 8192000000 10189325104 10608582656 1301366080 13.708 100 20 39 2 1311063040 5219456 4477504
17:35:31 55552 8192000000 10197976752 10615324672 1307525312 13.764 100 39 78 1 1323235168 7012336 8697520
17:37:34 6089 8192000000 10224945744 10641461248 1327156576 13.941 100 23 46 2 1337656736 5368112 5132048
17:38:47 12955 8192000000 10248696752 10660208640 1342606848 14.081 100 53 106 2 1362706464 7951728 12147888
17:40:05 20914 8192000000 10260860032 10679050240 1355226192 14.194 100 43 85 4 1375047104 10183728 9637184
17:41:33 26177 8192000000 10274504944 10698166272 1370280320 14.330 100 30 60 0 1388865136 11314112 7270704
17:43:03 44173 8192000000 10288334560 10714689536 1368461088 14.313 99 34 68 0 1388776160 12370832 7944240
17:45:19 30564 8192000000 10298095856 10721603584 1378529456 14.403 99 38 76 1 1403539632 15622656 9387520
17:46:57 3484 8192000000 10335879424 10755829760 1407158240 14.659 100 78 153 3 1448203296 22122544 18922512
17:48:08 5790 8192000000 10326022432 10743803904 1387602448 14.484 99 65 130 1 1426989984 23873904 15513632
17:49:22 12596 8192000000 10331174368 10752651264 1389404928 14.501 99 59 118 0 1433473184 28747328 15320928
17:50:38 5610 8192000000 10310659248 10730901504 1370207040 14.329 99 60 116 3 1408014528 23483856 14323632
17:51:51 4068 8192000000 10318845840 10725920768 1359327152 14.231 99 90 181 1 1408627312 27414144 21886016
17:53:00 1227 8192000000 10312579648 10716889088 1364856256 14.281 100 93 187 2 1416189600 28692064 22641280
17:54:05 6391 8192000000 10269460320 10683293696 1339015632 14.049 100 47 93 4 1381751440 29759168 12976640
17:55:21 1749 8192000000 10305481344 10707308544 1351998016 14.165 100 88 176 1 1409969936 36147264 21824656
17:56:26 18953 8192000000 10292069952 10719293440 1366651296 14.297 100 25 50 2 1404277648 31131632 6494720
17:57:52 9772 8192000000 10307978368 10735538176 1352111504 14.166 98 36 72 1 1396714800 35499584 9103712
17:59:05 25408 8192000000 10306686272 10733944832 1363136560 14.266 99 32 62 1 1400627712 29469408 8021744
18:00:32 19451 8192000000 10284743824 10728906752 1364753808 14.280 99 14 27 0 1384390896 15480432 4156656
18:01:54 14692 8192000000 10270409248 10705108992 1337550320 14.035 98 29 58 0 1363121120 18209584 7361216
18:03:20 21939 8192000000 10286794336 10708598784 1357765392 14.217 99 28 56 1 1376039648 11454144 6820112
18:04:42 42137 8192000000 10288141328 10724315136 1357989216 14.219 99 26 52 0 1382877888 18688736 6199936
18:06:25 42216 8192000000 10286136608 10737803264 1362083632 14.256 100 9 18 0 1387006624 22706720 2216272
18:08:17 54476 8192000000 10289202448 10745720832 1381854416 14.433 100 9 18 0 1395805216 12036320 1914480
18:10:12 70769 8192000000 10308876544 10749329408 1373593664 14.359 98 17 34 1 1388454320 11242688 3617968
18:12:24 138144 8192000000 10303320192 10752036864 1373509120 14.358 98 38 76 0 1398923776 15499632 9915024
18:15:43 12353 8192000000 10315614848 10758094848 1398241728 14.579 100 58 116 0 1419682384 8380368 13060288
18:16:57 27522 8192000000 10321417536 10767073280 1407569952 14.662 100 20 40 1 1421201792 9214544 4417296
18:18:31 41886 8192000000 10308675104 10766434304 1396613952 14.565 99 26 51 0 1414436240 12243744 5578544
18:20:19 22526 8192000000 10318036672 10773659648 1411400064 14.696 100 13 26 0 1429603104 14714368 3488672
18:21:42 138888 8192000000 10298368976 10754703360 1349481952 14.143 96 20 39 0 1365514240 11468544 4563744
18:25:21 59619 8192000000 10290990240 10750857216 1356975712 14.210 97 17 34 1 1378988816 18082496 3930608
18:27:21 47111 8192000000 10267752096 10733379584 1376044608 14.381 100 5 10 0 1388654000 11582512 1026880
18:29:11 52402 8192000000 10279509872 10737913856 1349183920 14.140 97 16 32 0 1362262816 9848096 3230800
18:31:09 60551 8192000000 10270665120 10731212800 1335897728 14.020 97 18 36 2 1353305312 13447408 3960176
18:33:11 64290 8192000000 10258962992 10703937536 1300686384 13.701 94 14 28 1 1316759056 12797408 3275264
18:35:20 55520 8192000000 10270312288 10723233792 1337750576 14.037 98 12 24 1 1362121792 21543296 2827920
18:37:43 73818 8192000000 10275773056 10726932480 1352150672 14.167 99 22 44 1 1369686816 12379248 5156896
18:39:57 38814 8192000000 10278599616 10722017280 1339783104 14.055 98 28 56 0 1355273888 9331008 6159776
18:41:37 54256 8192000000 10270951760 10729500672 1358025056 14.220 99 31 62 0 1380706560 15577872 7103632
18:43:39 85295 8192000000 10264084064 10708287488 1298063952 13.678 95 22 44 0 1317524416 14573808 4886656
18:46:13 26569 8192000000 10248524352 10703310848 1359289040 14.231 100 25 51 1 1378172880 12869648 6014192
18:47:41 61687 8192000000 10244389664 10706640896 1327408656 13.944 97 10 19 1 1344150640 14502640 2239344
18:49:43 52844 8192000000 10281480064 10740994048 1366333648 14.294 99 15 30 0 1391307008 21341104 3632256
18:51:36 87682 8192000000 10286694112 10743021568 1349927504 14.147 98 26 51 2 1383151008 26211760 7011744
18:54:14 70642 8192000000 10291171952 10749042688 1382031552 14.435 100 19 38 0 1396749280 10632240 4085488
18:56:26 35589 8192000000 10290527232 10737180672 1370293632 14.330 99 31 61 0 1385748656 8989808 6465216
18:58:03 131621 8192000000 10279136720 10736238592 1330734480 13.974 96 13 26 1 1348450128 14904496 2811152
19:01:52 44961 8192000000 10308855616 10756378624 1392643008 14.529 100 19 38 0 1411508016 14995232 3869776
19:03:37 120057 8192000000 10292842608 10753097728 1359176272 14.230 97 7 14 1 1379429120 18813920 1438928
19:06:41 80912 8192000000 10296861008 10754867200 1393497664 14.537 100 35 70 0 1409524928 8116864 7910400
19:09:06 33604 8192000000 10296588544 10758721536 1400909904 14.603 100 21 41 3 1414469248 8470224 5089120
19:10:47 54091 8192000000 10294148688 10756747264 1368260464 14.311 97 9 18 0 1381209632 10877424 2071744
19:12:41 36201 8192000000 10284729520 10746052608 1355852912 14.200 98 10 21 1 1369651712 11249632 2549168
19:14:21 54102 8192000000 10297293008 10758205440 1396311344 14.562 100 20 38 1 1409911808 9121040 4479424
19:16:16 74782 8192000000 10275145056 10739482624 1347841856 14.128 97 10 20 0 1358448784 8186288 2420640
19:18:31 68830 8192000000 10282535152 10748428288 1359787248 14.235 98 22 44 3 1378043456 13537600 4718608
19:20:41 52362 8192000000 10296076880 10737606656 1371404832 14.340 99 32 64 0 1387625632 8774752 7446048
19:22:36 77232 8192000000 10267889520 10710540288 1276711264 13.483 93 20 40 1 1291222320 10299520 4211536
19:25:05 49398 8192000000 10279873536 10721701888 1338549936 14.044 98 26 52 1 1358692272 14500848 5641488
19:26:55 170050 8192000000 10268377568 10726731776 1328588512 13.954 97 31 62 1 1347094656 11242496 7263648
19:30:56 63424 8192000000 10251205408 10721198080 1320383840 13.880 97 12 24 1 1343283072 20133360 2765872
19:33:00 43114 8192000000 10234778000 10692571136 1296459936 13.663 96 9 18 0 1305668560 7008000 2200624
19:34:43 73689 8192000000 10249280080 10715308032 1360230416 14.239 100 15 30 1 1374568640 10493120 3845104
19:37:00 62037 8192000000 9555896896 9897246720 659327024 7.448 49 7 14 0 664729104 4205264 1196816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-11-2021 19:39:33 to 13-11-2021 19:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 96 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-11-2021 19:39:49 to 13-11-2021 19:39:51.

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          248693
MON$OLDEST_ACTIVE               248694
MON$OLDEST_SNAPSHOT             248694
MON$NEXT_TRANSACTION            248694
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                       2036896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:227: 
:228: mscs-test-161.budzdorov.ru    Sat Nov 13 19:38:03 2021
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:230: 
--- end of diff output ---

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

13.11.21 19:39:52. 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
2021-11-13 19:39:54 No severe PSQL-related problems occured
13.11.21 19:39:55. End of report.