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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15878624 kB
MemAvailable 30913244 kB
Buffers 0 kB
Cached 15841832 kB
SwapCached 2512 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-15 19:38:25
DTS_END                         2022-08-15 19:38:25.7480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54000
TRN_ID                          243956
ATT_ID                          610
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: 9258.35 20917 1111002
customer order: creation 177.88 347 21345
customer order: refuse 15.68 329 1882
order to supplier: creation 47.39 1283 5687
order to supplier: removal 3.19 107050 383
invoice (draft): creation 48.02 3364 5762
invoice (draft): removal 50.72 998 6086
invoice accept: apply 44.11 16943 5293
invoice accept: cancel 45.87 821 5504
customer reserve: creation 3995.35 171 479442
customer reserve: removal 4527.93 58 543351
realization accept: apply 86.73 161 10407
realization accept: cancel 81.04 105 9725
payment from customer: creation 62.05 98 7446
payment from customer: removal 5.77 76 692
payment to supplier: creation 50.75 132 6090
payment to supplier: removal 3.57 62 428
service: total inventory turnovers 4.63 1205 555
service: total monetary turnovers 6.74 31 809
service: aggregate perf. data 0.93 17120 111
service: refresh index statistics 0.03 267984 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2022 19:39:47 to 15-08-2022 19:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2697
TEST_TIME 2 3379
TEST_TIME 3 3757
TEST_TIME 4 4499
TEST_TIME 5 4954
TEST_TIME 6 4723
TEST_TIME 7 5100
TEST_TIME 8 4997
TEST_TIME 9 5677
TEST_TIME 10 6575
TEST_TIME 11 6448
TEST_TIME 12 6383
TEST_TIME 13 6641
TEST_TIME 14 6942
TEST_TIME 15 7183
TEST_TIME 16 7471
TEST_TIME 17 7568
TEST_TIME 18 8086
TEST_TIME 19 8402
TEST_TIME 20 8417
TEST_TIME 21 9131
TEST_TIME 22 9033
TEST_TIME 23 9017
TEST_TIME 24 9014
TEST_TIME 25 8849
TEST_TIME 26 8750
TEST_TIME 27 8759
TEST_TIME 28 8651
TEST_TIME 29 8731
TEST_TIME 30 9475
TEST_TIME 31 10408
TEST_TIME 32 10785
TEST_TIME 33 10701
TEST_TIME 34 10844
TEST_TIME 35 10614
TEST_TIME 36 10356
TEST_TIME 37 10279
TEST_TIME 38 10273
TEST_TIME 39 10161
TEST_TIME 40 10088
TEST_TIME 41 9997
TEST_TIME 42 10005
TEST_TIME 43 10081
TEST_TIME 44 10065
TEST_TIME 45 10179
TEST_TIME 46 10063
TEST_TIME 47 9990
TEST_TIME 48 9879
TEST_TIME 49 9860
TEST_TIME 50 9771
TEST_TIME 51 9703
TEST_TIME 52 9852
TEST_TIME 53 9801
TEST_TIME 54 9744
TEST_TIME 55 9757
TEST_TIME 56 9715
TEST_TIME 57 9732
TEST_TIME 58 9735
TEST_TIME 59 9679
TEST_TIME 60 9766
TEST_TIME 61 9855
TEST_TIME 62 9871
TEST_TIME 63 9952
TEST_TIME 64 9931
TEST_TIME 65 9941
TEST_TIME 66 9993
TEST_TIME 67 10042
TEST_TIME 68 9953
TEST_TIME 69 9819
TEST_TIME 70 9872
TEST_TIME 71 9807
TEST_TIME 72 9784
TEST_TIME 73 9702
TEST_TIME 74 9632
TEST_TIME 75 9563
TEST_TIME 76 9607
TEST_TIME 77 9546
TEST_TIME 78 9576
TEST_TIME 79 9552
TEST_TIME 80 9593
TEST_TIME 81 9589
TEST_TIME 82 9606
TEST_TIME 83 9596
TEST_TIME 84 9627
TEST_TIME 85 9592
TEST_TIME 86 9608
TEST_TIME 87 9625
TEST_TIME 88 9749
TEST_TIME 89 9714
TEST_TIME 90 9676
TEST_TIME 91 9596
TEST_TIME 92 9558
TEST_TIME 93 9575
TEST_TIME 94 9519
TEST_TIME 95 9466
TEST_TIME 96 9515
TEST_TIME 97 9495
TEST_TIME 98 9529
TEST_TIME 99 9491
TEST_TIME 100 9513
TEST_TIME 101 9538
TEST_TIME 102 9577
TEST_TIME 103 9586
TEST_TIME 104 9593
TEST_TIME 105 9619
TEST_TIME 106 9598
TEST_TIME 107 9649
TEST_TIME 108 9663
TEST_TIME 109 9584
TEST_TIME 110 9518
TEST_TIME 111 9527
TEST_TIME 112 9513
TEST_TIME 113 9464
TEST_TIME 114 9433
TEST_TIME 115 9394
TEST_TIME 116 9395
TEST_TIME 117 9351
TEST_TIME 118 9332
TEST_TIME 119 9323
TEST_TIME 120 9262

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2022 19:39:51 to 15-08-2022 19:39:54.

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 1555584 1555584 0 0.00 0 19681 6 0 0
doc_list_biud 1555584 1555584 0 0.00 0 53862 26 0 0
sp_add_invoice_to_stock 5382 5293 89 1.65 40 174564 16944 0 89
sp_cancel_adding_invoice 5504 5504 0 0.00 4 73547 821 0 0
sp_cancel_client_order 1882 1882 0 0.00 4 17025 330 0 0
sp_cancel_customer_reserve 543351 543351 0 0.00 0 28140 58 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 4891 77 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 4501 62 0 0
sp_cancel_supplier_invoice 6095 6086 9 0.14 1 53866 998 0 9
sp_cancel_supplier_order 383 383 0 0.00 13 1034539 107051 0 0
sp_cancel_write_off 9725 9725 0 0.00 1 10154 106 0 0
sp_client_order 21345 21345 0 0.00 3 24577 348 0 0
sp_customer_reserve 479442 479442 0 0.00 0 76879 171 0 0
sp_fill_shopping_cart 32767 32767 0 0.00 1 14487 38 0 0
sp_fill_shopping_cart_clo_res 479430 479430 0 0.00 0 21332 14 0 0
sp_get_clo_for_invoice 5281 5281 0 0.00 0 44853 378 0 0
sp_kill_cost_storno 16349 16349 0 0.00 0 9310 56 0 0
sp_kill_qstorno_ret_qs2qd 1102896 1102896 0 0.00 0 53859 33 0 0
sp_kill_qty_storno 582619 582619 0 0.00 0 53861 70 0 0
sp_lock_selected_doc 21674 21674 0 0.00 0 9676 7 0 0
sp_make_cost_storno 29224 29224 0 0.00 0 19681 47 0 0
sp_multiply_rows_for_pdistr 29224 29224 0 0.00 0 7973 25 0 0
sp_multiply_rows_for_qdistr 21345 21345 0 0.00 0 13368 147 0 0
sp_pay_from_customer 7446 7446 0 0.00 4 11051 99 0 0
sp_pay_to_supplier 6090 6090 0 0.00 4 19687 132 0 0
sp_payment_common 13536 13536 0 0.00 1 13091 28 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 13520 295 0 0
sp_qd_handle_on_invoice_upd_sts 10785 10785 0 0.00 0 21626 309 0 0
sp_qd_handle_on_reserve_upd_sts 10407 10407 0 0.00 0 15865 45 0 0
sp_reserve_write_off 10407 10407 0 0.00 6 17398 162 0 0
sp_supplier_invoice 5762 5762 0 0.00 5 80013 3364 0 0
sp_supplier_order 5687 5687 0 0.00 4 49477 1283 0 0
srv_aggregate_perf_data 112 111 1 0.89 2392 261212 17120 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 774 166641 44169 0 0
srv_find_qd_qs_mism 1061792 1061792 0 0.00 0 16392 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 28 64272 1205 0 0
srv_make_money_saldo 809 809 0 0.00 1 3963 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 214842 354449 267984 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5293 5293 0 0.00 0 18528 160 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 2560 100 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 14032 28 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 642 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 15542 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 129 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 506 8 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 29155 1934 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 4001 9 0 0
t$perf-norm:sp_client_order 21345 21345 0 0.00 0 19019 19 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 10975 24 0 0
t$perf-norm:sp_pay_from_customer 7446 7446 0 0.00 0 16454 14 0 0
t$perf-norm:sp_pay_to_supplier 6090 6090 0 0.00 0 13600 14 0 0
t$perf-norm:sp_reserve_write_off 10407 10407 0 0.00 0 16718 17 0 0
t$perf-norm:sp_supplier_invoice 5762 5762 0 0.00 0 7106 21 0 0
t$perf-norm:sp_supplier_order 5687 5687 0 0.00 0 13742 20 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 884 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 751 14 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 2998 27 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 17694 38 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10740 10562 178 1.65 0 16988 23 0 178
v_all_customers 39222 39222 0 0.00 0 58 1 0 0
v_all_suppliers 31486 31486 0 0.00 0 148 1 0 0
v_all_wares 42690 42690 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1271 8 0 0
v_cancel_client_order 3764 3764 0 0.00 0 17001 9 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1374 5 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 17045 17 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4465 10 0 0
v_cancel_supplier_invoice 846 828 18 2.12 0 1704 16 0 18
v_cancel_supplier_order 766 766 0 0.00 0 2178 10 0 0
v_cancel_write_off 2086 2086 0 0.00 0 9084 24 0 0
v_max_id_clo_ord 5687 5687 0 0.00 0 4935 3 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 5929 26 0 0
v_max_id_ord_sup 5735 5735 0 0.00 0 3744 5 0 0
v_max_non_paid_invoice 1769 1769 0 0.00 0 4241 7 0 0
v_max_non_paid_realizn 3845 3845 0 0.00 0 2065 4 0 0
v_min_id_clo_ord 5687 5687 0 0.00 0 9646 6 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 7252 40 0 0
v_min_id_ord_sup 5735 5735 0 0.00 0 5568 10 0 0
v_min_non_paid_invoice 6090 6090 0 0.00 0 13085 17 0 0
v_min_non_paid_realizn 7446 7446 0 0.00 0 6680 17 0 0
v_our_firm 10670 10670 0 0.00 0 60 1 0 0
v_reserve_write_off 20814 20814 0 0.00 0 17001 11 0 0
x_lock_depdocs_on_canc_invoice 11176 11176 0 0.00 0 14293 120 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 6057 360 0 0
x_make_qstorno_1000_1200 5687 5687 0 0.00 1 49472 1211 0 0
x_make_qstorno_1200_2000 5762 5762 0 0.00 2 79349 3210 0 0
x_make_qstorno_2100_3300 479442 479442 0 0.00 0 76878 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2022 19:39:55 to 15-08-2022 19:39:59.

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:29 3 8192000000 8800634016 8812658688 6652880 0.081 3 1 2 0 6753808 58144 42784
17:09:30 14325 8192000000 9894820208 10202763264 983671920 10.720 100 88 176 0 1011893856 5424288 22797648
17:10:52 5170 8192000000 9931258592 10245783552 1014387056 11.018 100 89 178 0 1044834224 7431104 23016064
17:12:02 9147 8192000000 9954093392 10275643392 1040120960 11.266 100 91 182 0 1071665600 8447312 23097328
17:13:14 9347 8192000000 10003387616 10342985728 1085285728 11.698 100 74 148 0 1112507104 9510192 17711184
17:14:27 8767 8192000000 10053086176 10405416960 1142222640 12.236 100 78 156 2 1172101552 12004960 17873952
17:15:48 4641 8192000000 10098949856 10465234944 1191589680 12.698 100 74 147 0 1219956256 12246416 16120160
17:16:55 3034 8192000000 10136468816 10509807616 1229712832 13.051 100 70 139 0 1257127440 12916832 14497776
17:18:00 4531 8192000000 10171420624 10550861824 1262004448 13.348 100 72 144 0 1290671824 13498912 15168464
17:19:06 1335 8192000000 10213549376 10595438592 1294090592 13.641 100 93 187 0 1328081472 12313856 21677024
17:20:08 902 8192000000 10215389328 10607620096 1306875648 13.758 100 83 165 1 1336756144 11834560 18045936
17:21:09 1943 8192000000 10203998464 10609582080 1310267792 13.789 100 48 94 0 1330420160 9626960 10525408
17:22:14 4198 8192000000 10204608208 10619084800 1320248800 13.879 100 32 65 0 1334275632 7124896 6901936
17:23:19 75630 8192000000 10213083040 10635431936 1324182960 13.915 100 28 50 1 1335279792 5244064 5852768
17:25:38 7364 8192000000 10218036384 10642370560 1330606576 13.973 100 27 54 1 1342488240 5292192 6589472
17:26:46 8371 8192000000 10220014784 10640990208 1329496320 13.963 100 15 28 0 1338391904 5489664 3405920
17:27:54 66390 8192000000 10204495920 10627567616 1289452912 13.599 97 22 44 0 1299879248 5314896 5111440
17:30:02 8119 8192000000 10154436624 10561613824 1242481472 13.169 97 30 60 1 1255652224 6061072 7109680
17:31:10 6446 8192000000 10149334352 10551840768 1225153376 13.009 96 25 50 1 1236021872 5248544 5619952
17:32:17 11892 8192000000 10118416304 10513756160 1180118528 12.591 95 16 25 0 1188266608 5124992 3023088
17:33:29 23844 8192000000 10146488544 10551025664 1256642896 13.299 100 23 46 0 1268202080 6116544 5442640
17:34:58 8665 8192000000 10172361312 10581348352 1279477584 13.508 100 25 50 0 1291041216 5526272 6037360
17:36:08 8139 8192000000 10187223856 10600230912 1294903776 13.649 100 18 36 0 1305136656 6422544 3810336
17:37:20 16640 8192000000 10203817008 10615033856 1310354448 13.789 100 20 39 0 1320894960 4975136 5565376
17:38:40 114287 8192000000 10248329968 10663333888 1340175360 14.059 100 37 74 0 1356922144 7529376 9217408
17:41:37 26462 8192000000 10271323328 10690592768 1369113120 14.319 100 52 101 0 1393029504 12245520 11670864
17:43:14 17165 8192000000 10282622512 10707193856 1376143952 14.382 100 47 94 1 1398762240 11626800 10991488
17:44:37 10507 8192000000 10299930976 10723889152 1389639616 14.503 100 37 73 0 1410065952 11363008 9063328
17:45:53 20280 8192000000 10308966288 10736603136 1395911376 14.559 100 40 78 0 1419244576 13809072 9524128
17:47:27 6409 8192000000 10315810800 10745200640 1403235424 14.624 100 56 113 1 1432815552 15767584 13812544
17:48:40 23810 8192000000 10329446960 10760802304 1413501824 14.715 100 50 100 0 1440404704 15636592 11266288
17:50:07 4558 8192000000 10341026192 10767949824 1418015088 14.755 100 62 124 1 1450712336 18240688 14456560
17:51:15 16746 8192000000 10347973904 10779189248 1404625104 14.636 99 51 102 2 1444303696 26387936 13290656
17:52:42 3442 8192000000 10341382256 10758512640 1398240608 14.579 100 67 135 0 1443896656 29197312 16458736
17:53:51 2473 8192000000 10340217056 10753122304 1395486512 14.555 100 75 150 0 1442525264 29258768 17779984
17:55:01 6456 8192000000 10359356096 10778173440 1409217520 14.677 100 67 135 0 1457923024 32213600 16491904
17:56:12 2957 8192000000 10331400656 10749689856 1381088480 14.426 99 71 139 0 1436838992 38148480 17602032
17:57:17 4759 8192000000 10324726816 10748461056 1387493952 14.484 100 45 86 0 1434083952 35482064 11107936
17:58:26 774 8192000000 10342404624 10750488576 1379457056 14.412 99 88 173 1 1437992496 37333360 21202080
17:59:33 14773 8192000000 10312914208 10746572800 1340157424 14.059 97 38 76 2 1399028256 48508032 10362800
18:00:52 15713 8192000000 10293558912 10722127872 1334349248 14.006 98 32 64 0 1384790928 41808096 8633584
18:02:15 7181 8192000000 10313436144 10740465664 1373470976 14.358 100 45 90 0 1427190032 42346208 11372848
18:03:28 10438 8192000000 10338941216 10758795264 1381556000 14.430 100 41 82 0 1438498832 46028336 10914496
18:04:54 12421 8192000000 10337236448 10774450176 1378709936 14.405 99 34 69 1 1440509280 52492912 9306432
18:06:11 12542 8192000000 10328482528 10754187264 1360062448 14.238 100 31 62 1 1433044288 63463808 9518032
18:07:32 27897 8192000000 10332701856 10766454784 1350542976 14.152 100 30 58 1 1433741984 73651824 9547184
18:09:05 33922 8192000000 10303592336 10744922112 1331057328 13.977 97 16 32 0 1388643920 52997776 4588816
18:10:44 20961 8192000000 10291699088 10722828288 1369422000 14.322 100 32 61 2 1382390688 5472720 7495968
18:12:05 166641 8192000000 10275355056 10715025408 1329734576 13.965 96 20 40 0 1343821232 8966384 5120272
18:15:57 55338 8192000000 10279795904 10723749888 1344240192 14.096 97 34 68 3 1360631136 8283968 8106976
18:18:15 38980 8192000000 10297002336 10751877120 1394575888 14.547 100 20 40 0 1410493808 10676624 5241296
18:20:15 38331 8192000000 10303955872 10752397312 1389836176 14.504 100 16 32 0 1408992304 15381872 3774256
18:22:05 24482 8192000000 10336213984 10772529152 1405125808 14.641 100 44 88 1 1427235632 11163472 10946352
18:23:31 20768 8192000000 10306996688 10764029952 1404950160 14.639 100 16 33 0 1417937504 9422608 3564736
18:25:05 40199 8192000000 10294919712 10757120000 1382911888 14.443 98 7 14 0 1390161712 5726656 1523168
18:26:49 25426 8192000000 10287844064 10751279104 1397370576 14.572 100 12 24 0 1408119168 7889744 2858848
18:28:26 113631 8192000000 10321380112 10790305792 1405885024 14.647 100 18 35 1 1424159920 13437712 4837184
18:31:39 10862 8192000000 10321608192 10769432576 1416247616 14.739 100 51 103 0 1434985872 6586480 12151776
18:32:52 78813 8192000000 10304550320 10780516352 1394548592 14.546 98 15 29 0 1407706688 9857488 3300608
18:35:11 53288 8192000000 10314038704 10776428544 1382009408 14.435 98 11 22 0 1401301856 16334576 2957872
18:37:08 26188 8192000000 10306024384 10760712192 1387098160 14.480 99 21 42 0 1406394208 14667360 4628688
18:38:44 37309 8192000000 10282378464 10731970560 1346683488 14.118 98 33 66 0 1369597360 15778704 7135168
18:40:27 56734 8192000000 10282301072 10736689152 1351321120 14.159 98 18 37 0 1370423984 15028080 4074784
18:42:24 42915 8192000000 10270537600 10714198016 1309837344 13.785 95 34 68 0 1326137968 9324240 6976384
18:44:10 35838 8192000000 10259099584 10691100672 1315894032 13.840 98 35 69 0 1342931904 19514240 7523632
18:45:49 131427 8192000000 10276663360 10715131904 1352498496 14.170 100 20 40 2 1372464112 15625952 4339664
18:49:04 13395 8192000000 10251081808 10713391104 1348837648 14.137 100 15 30 0 1365962144 13818400 3306096
18:51:20 75029 8192000000 10245873744 10700054528 1316833472 13.848 97 11 22 0 1329880128 10392032 2654624
18:53:35 47937 8192000000 10243175056 10699374592 1335909360 14.021 99 12 24 0 1348874176 9585328 3379488
18:55:23 66102 8192000000 10257071232 10718523392 1340788896 14.065 98 13 26 0 1355006528 10796368 3421264
18:57:30 41534 8192000000 10266632224 10726850560 1351813552 14.164 99 23 46 0 1372644560 15318848 5512160
18:59:19 38185 8192000000 10273731088 10726940672 1367632064 14.306 100 21 42 0 1382910368 10583408 4694896
19:01:03 81833 8192000000 10285701856 10750398464 1377468992 14.394 100 13 26 0 1394368496 13981888 2917616
19:03:25 12512 8192000000 10286794896 10726981632 1367905312 14.308 100 49 98 0 1388388480 9483456 10999712
19:04:42 80528 8192000000 10307407696 10763530240 1371693600 14.342 99 30 59 1 1391754576 14165040 5895936
19:07:11 63966 8192000000 10307815360 10738061312 1365320320 14.285 98 41 79 2 1384235408 10122016 8793072
19:09:22 84822 8192000000 10311058944 10763485184 1392927248 14.532 100 24 47 0 1404835696 6929424 4979024
19:11:50 44995 8192000000 10291386560 10763988992 1394422304 14.545 100 5 10 0 1402153776 6944688 786784
19:13:41 91604 8192000000 10292282192 10761871360 1345376304 14.106 96 8 16 0 1359484096 12196560 1911232
19:16:13 62585 8192000000 10302657312 10757767168 1364993984 14.282 98 16 33 0 1378191920 9345456 3852480
19:18:16 93034 8192000000 10302033680 10771689472 1351678224 14.163 97 9 18 0 1371326000 17207600 2440176
19:20:58 65679 8192000000 10299949872 10757615616 1384393792 14.456 100 17 34 0 1396564608 9104416 3066400
19:23:07 83357 8192000000 10297205616 10747351040 1323283024 13.906 95 19 39 0 1339464576 12100448 4081104
19:25:34 98565 8192000000 10266415440 10734387200 1299756032 13.693 94 16 32 0 1317115840 14022768 3337040
19:28:54 60829 8192000000 10259973888 10722963456 1330865408 13.975 97 27 54 0 1350191232 13133056 6192768
19:30:56 51370 8192000000 10254659616 10711171072 1342240704 14.078 98 9 18 0 1352174320 8165952 1767664
19:32:55 79215 8192000000 10259534880 10720931840 1340681632 14.064 98 7 14 0 1350684064 8205280 1797152
19:35:23 87248 8192000000 10251263664 10711138304 1295054560 13.650 95 10 20 0 1305437376 8574816 1808000
19:37:55 32398 8192000000 9878645312 10267312128 963056384 10.519 72 26 52 0 973972816 5501520 5414912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-08-2022 19:40:01 to 15-08-2022 19:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 89 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2022 19:40:17 to 15-08-2022 19:40:19.

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          242631
MON$OLDEST_ACTIVE               244254
MON$OLDEST_SNAPSHOT             244254
MON$NEXT_TRANSACTION            244254
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                       2032736
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:307: 
:308: mscs-test-161.budzdorov.ru    Mon Aug 15 19:38:29 2022
:309:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:310: 
--- end of diff output ---

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

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