Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2022 22: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 16281148 kB
MemAvailable 31302564 kB
Buffers 0 kB
Cached 15361000 kB
SwapCached 1196 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.9
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-01-03 22:38:01
DTS_END                         2022-01-03 22:38:01.3890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54918
TRN_ID                          253316
ATT_ID                          667
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: 9554.92 18380 1146590
customer order: creation 192.75 310 23130
customer order: refuse 16.48 354 1977
order to supplier: creation 50.84 1271 6101
order to supplier: removal 3.16 98977 379
invoice (draft): creation 51.05 3791 6126
invoice (draft): removal 51.49 752 6179
invoice accept: apply 46.23 16127 5547
invoice accept: cancel 46.38 847 5566
customer reserve: creation 4263.40 158 511608
customer reserve: removal 4518.83 54 542259
realization accept: apply 93.99 193 11279
realization accept: cancel 77.13 110 9255
payment from customer: creation 65.78 111 7893
payment from customer: removal 6.30 92 756
payment to supplier: creation 53.73 136 6447
payment to supplier: removal 3.81 90 457
service: total inventory turnovers 5.28 1052 634
service: total monetary turnovers 7.44 34 893
service: aggregate perf. data 0.83 24362 100
service: refresh index statistics 0.03 218781 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2022 22:38:47 to 03-01-2022 22:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6193
TEST_TIME 2 7064
TEST_TIME 3 7194
TEST_TIME 4 6771
TEST_TIME 5 7348
TEST_TIME 6 7706
TEST_TIME 7 7746
TEST_TIME 8 8033
TEST_TIME 9 7810
TEST_TIME 10 7655
TEST_TIME 11 7613
TEST_TIME 12 7758
TEST_TIME 13 7769
TEST_TIME 14 7757
TEST_TIME 15 8006
TEST_TIME 16 8262
TEST_TIME 17 8766
TEST_TIME 18 8845
TEST_TIME 19 8868
TEST_TIME 20 9128
TEST_TIME 21 9213
TEST_TIME 22 9598
TEST_TIME 23 9620
TEST_TIME 24 9699
TEST_TIME 25 10123
TEST_TIME 26 10547
TEST_TIME 27 10767
TEST_TIME 28 10941
TEST_TIME 29 11357
TEST_TIME 30 11159
TEST_TIME 31 11092
TEST_TIME 32 11118
TEST_TIME 33 11032
TEST_TIME 34 10924
TEST_TIME 35 10947
TEST_TIME 36 10767
TEST_TIME 37 10704
TEST_TIME 38 10666
TEST_TIME 39 10520
TEST_TIME 40 10705
TEST_TIME 41 10780
TEST_TIME 42 10677
TEST_TIME 43 10502
TEST_TIME 44 10454
TEST_TIME 45 10266
TEST_TIME 46 10413
TEST_TIME 47 10269
TEST_TIME 48 10132
TEST_TIME 49 10099
TEST_TIME 50 10021
TEST_TIME 51 10087
TEST_TIME 52 10134
TEST_TIME 53 10171
TEST_TIME 54 10084
TEST_TIME 55 10046
TEST_TIME 56 10004
TEST_TIME 57 9907
TEST_TIME 58 9830
TEST_TIME 59 9768
TEST_TIME 60 9753
TEST_TIME 61 9721
TEST_TIME 62 9633
TEST_TIME 63 9625
TEST_TIME 64 9576
TEST_TIME 65 9631
TEST_TIME 66 9658
TEST_TIME 67 9668
TEST_TIME 68 9689
TEST_TIME 69 9728
TEST_TIME 70 9765
TEST_TIME 71 9803
TEST_TIME 72 9822
TEST_TIME 73 9800
TEST_TIME 74 9906
TEST_TIME 75 9836
TEST_TIME 76 9971
TEST_TIME 77 10008
TEST_TIME 78 9894
TEST_TIME 79 9864
TEST_TIME 80 9804
TEST_TIME 81 9774
TEST_TIME 82 9737
TEST_TIME 83 9726
TEST_TIME 84 9664
TEST_TIME 85 9660
TEST_TIME 86 9600
TEST_TIME 87 9651
TEST_TIME 88 9650
TEST_TIME 89 9611
TEST_TIME 90 9644
TEST_TIME 91 9684
TEST_TIME 92 9668
TEST_TIME 93 9670
TEST_TIME 94 9630
TEST_TIME 95 9669
TEST_TIME 96 9831
TEST_TIME 97 9811
TEST_TIME 98 9786
TEST_TIME 99 9857
TEST_TIME 100 9865
TEST_TIME 101 9865
TEST_TIME 102 9802
TEST_TIME 103 9740
TEST_TIME 104 9735
TEST_TIME 105 9766
TEST_TIME 106 9734
TEST_TIME 107 9689
TEST_TIME 108 9667
TEST_TIME 109 9652
TEST_TIME 110 9594
TEST_TIME 111 9587
TEST_TIME 112 9545
TEST_TIME 113 9580
TEST_TIME 114 9592
TEST_TIME 115 9586
TEST_TIME 116 9581
TEST_TIME 117 9620
TEST_TIME 118 9661
TEST_TIME 119 9638
TEST_TIME 120 9557

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2022 22:38:51 to 03-01-2022 22:38: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 1618876 1618876 0 0.00 0 32720 6 0 0
doc_list_biud 1618876 1618876 0 0.00 0 34166 24 0 0
sp_add_invoice_to_stock 5635 5547 88 1.56 46 162167 16128 0 88
sp_cancel_adding_invoice 5566 5566 0 0.00 5 59838 847 0 0
sp_cancel_client_order 1977 1977 0 0.00 4 7514 354 0 0
sp_cancel_customer_reserve 542259 542259 0 0.00 1 34304 54 0 0
sp_cancel_pay_from_customer 756 756 0 0.00 2 3931 92 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 2 9422 91 0 0
sp_cancel_supplier_invoice 6196 6179 17 0.27 2 32631 752 0 17
sp_cancel_supplier_order 379 379 0 0.00 6 1055983 98977 0 0
sp_cancel_write_off 9255 9255 0 0.00 1 10052 111 0 0
sp_client_order 23130 23130 0 0.00 3 31518 311 0 0
sp_customer_reserve 511608 511608 0 0.00 0 91737 158 0 0
sp_fill_shopping_cart 35320 35320 0 0.00 1 22152 38 0 0
sp_fill_shopping_cart_clo_res 511598 511598 0 0.00 0 20172 12 0 0
sp_get_clo_for_invoice 5537 5537 0 0.00 0 23674 291 0 0
sp_kill_cost_storno 16034 16034 0 0.00 0 32699 63 0 0
sp_kill_qstorno_ret_qs2qd 1100331 1100331 0 0.00 0 32605 30 0 0
sp_kill_qty_storno 582431 582431 0 0.00 0 34166 65 0 0
sp_lock_selected_doc 23084 23084 0 0.00 0 10263 8 0 0
sp_make_cost_storno 31156 31156 0 0.00 0 13639 56 0 0
sp_multiply_rows_for_pdistr 31156 31156 0 0.00 0 8596 30 0 0
sp_multiply_rows_for_qdistr 23130 23130 0 0.00 0 12244 137 0 0
sp_pay_from_customer 7893 7893 0 0.00 4 20868 112 0 0
sp_pay_to_supplier 6447 6447 0 0.00 4 17698 137 0 0
sp_payment_common 14340 14340 0 0.00 1 20757 35 0 0
sp_qd_handle_on_cancel_clo 1977 1977 0 0.00 0 6458 319 0 0
sp_qd_handle_on_invoice_upd_sts 11103 11103 0 0.00 0 14571 321 0 0
sp_qd_handle_on_reserve_upd_sts 11279 11279 0 0.00 0 16597 60 0 0
sp_reserve_write_off 11279 11279 0 0.00 6 16619 193 0 0
sp_supplier_invoice 6126 6126 0 0.00 6 112742 3792 0 0
sp_supplier_order 6101 6101 0 0.00 4 36384 1271 0 0
srv_aggregate_perf_data 101 100 1 0.99 3046 327812 24362 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 2227 137610 43408 0 0
srv_find_qd_qs_mism 1092896 1092896 0 0.00 0 7271 5 0 0
srv_make_invnt_saldo 634 634 0 0.00 23 122838 1052 0 0
srv_make_money_saldo 893 893 0 0.00 1 7184 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 152192 315901 218781 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 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 5547 5547 0 0.00 0 10270 155 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4285 93 0 0
t$perf-norm:sp_cancel_client_order 1977 1977 0 0.00 0 7056 18 0 0
t$perf-norm:sp_cancel_customer_reserve 793 793 0 0.00 0 1179 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 756 756 0 0.00 0 2050 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 7796 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 504 13 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 41979 1993 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 554 4 0 0
t$perf-norm:sp_client_order 23130 23130 0 0.00 0 7217 16 0 0
t$perf-norm:sp_customer_reserve 1181 1181 0 0.00 0 1207 6 0 0
t$perf-norm:sp_pay_from_customer 7893 7893 0 0.00 0 4879 8 0 0
t$perf-norm:sp_pay_to_supplier 6447 6447 0 0.00 0 6569 10 0 0
t$perf-norm:sp_reserve_write_off 11279 11279 0 0.00 0 14847 13 0 0
t$perf-norm:sp_supplier_invoice 6126 6126 0 0.00 0 6521 18 0 0
t$perf-norm:sp_supplier_order 6101 6101 0 0.00 0 6137 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 105 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 634 634 0 0.00 0 4217 27 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 1362 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 11250 11074 176 1.56 0 9428 20 0 176
v_all_customers 42522 42522 0 0.00 0 16 1 0 0
v_all_suppliers 33570 33570 0 0.00 0 189 1 0 0
v_all_wares 46260 46260 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 3445 12 0 0
v_cancel_client_order 3954 3954 0 0.00 0 5414 8 0 0
v_cancel_customer_prepayment 1512 1512 0 0.00 0 3835 11 0 0
v_cancel_customer_reserve 1586 1586 0 0.00 0 483 3 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 1393 4 0 0
v_cancel_supplier_invoice 868 834 34 3.91 0 1979 16 0 34
v_cancel_supplier_order 758 758 0 0.00 0 1394 5 0 0
v_cancel_write_off 2118 2118 0 0.00 0 1663 16 0 0
v_max_id_clo_ord 6101 6101 0 0.00 0 426 1 0 0
v_max_id_clo_res 1181 1181 0 0.00 0 1552 11 0 0
v_max_id_ord_sup 6089 6089 0 0.00 0 4701 3 0 0
v_max_non_paid_invoice 1852 1852 0 0.00 0 2701 5 0 0
v_max_non_paid_realizn 3997 3997 0 0.00 0 3470 5 0 0
v_min_id_clo_ord 6101 6101 0 0.00 0 3415 5 0 0
v_min_id_clo_res 1181 1181 0 0.00 0 3589 8 0 0
v_min_id_ord_sup 6089 6089 0 0.00 0 4657 4 0 0
v_min_non_paid_invoice 6447 6447 0 0.00 0 12452 22 0 0
v_min_non_paid_realizn 7893 7893 0 0.00 0 11838 23 0 0
v_our_firm 11530 11530 0 0.00 0 20 1 0 0
v_reserve_write_off 22558 22558 0 0.00 0 3342 5 0 0
x_lock_depdocs_on_canc_invoice 11328 11328 0 0.00 0 26641 116 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 7756 355 0 0
x_make_qstorno_1000_1200 6101 6101 0 0.00 2 36299 1194 0 0
x_make_qstorno_1200_2000 6126 6126 0 0.00 3 109703 3648 0 0
x_make_qstorno_2100_3300 511608 511608 0 0.00 0 91735 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2022 22:38:55 to 03-01-2022 22:38: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
20:08:05 11 8192000000 8813362192 8829640704 16234416 0.197 4 4 5 1 16470240 87568 148256
20:09:05 30213 8192000000 9895368864 10206216192 977388336 10.659 100 87 174 0 1006191600 5923744 22879520
20:10:53 15775 8192000000 9941925456 10263375872 1025845056 11.128 100 87 174 0 1056221264 8274240 22101968
20:12:13 21541 8192000000 9987743808 10324889600 1070497776 11.557 100 86 172 1 1100520672 9409008 20613888
20:13:41 9958 8192000000 10038785280 10389790720 1131098288 12.132 100 84 166 0 1161458432 10339248 20020896
20:14:54 9563 8192000000 10116425520 10479206400 1189951808 12.683 100 82 164 1 1221151040 12633200 18566032
20:16:08 6456 8192000000 10140486544 10517991424 1230125328 13.055 100 73 148 0 1258109232 11123808 16860096
20:17:16 3752 8192000000 10169730560 10552733696 1252325696 13.260 100 70 140 0 1280787648 12604960 15856992
20:18:22 2185 8192000000 10204367360 10588737536 1285821872 13.566 100 97 191 2 1321693056 14348144 21523040
20:19:26 22875 8192000000 10205736608 10602422272 1300137600 13.696 100 63 126 1 1326676976 12685712 13853664
20:20:49 9296 8192000000 10198939456 10608934912 1310280480 13.789 100 18 36 0 1320072480 6312448 3479552
20:21:59 11667 8192000000 10201167904 10620284928 1319297056 13.870 100 24 48 0 1331243872 6565840 5380976
20:23:13 46963 8192000000 10210626912 10636488704 1324690560 13.919 100 40 78 1 1339333504 5151216 9491728
20:25:03 7713 8192000000 10229166928 10649346048 1332853920 13.993 100 22 44 0 1342820080 5655600 4310560
20:26:12 9877 8192000000 10236112016 10655031296 1340214752 14.059 100 15 28 2 1348910624 5644048 3051824
20:27:22 63959 8192000000 10219450608 10637017088 1314544896 13.827 99 34 63 2 1326927712 5328448 7054368
20:29:29 3002 8192000000 10187813456 10593951744 1280840688 13.521 98 40 81 2 1296440096 6996288 8603120
20:30:34 9075 8192000000 10141006048 10532667392 1205622752 12.829 95 17 34 2 1214088416 4597040 3868624
20:31:44 6566 8192000000 10141896848 10539405312 1195180960 12.732 95 32 63 3 1212447872 9657104 7609808
20:32:54 33925 8192000000 10129906416 10530230272 1176558624 12.558 93 20 40 1 1190352672 9128320 4665728
20:34:28 10960 8192000000 10175302576 10586656768 1286193392 13.570 100 29 58 2 1299103632 6207872 6702368
20:35:39 11931 8192000000 10188531616 10599931904 1295701744 13.656 100 20 39 1 1306737600 5467520 5568336
20:36:52 10195 8192000000 10193141152 10608734208 1302803232 13.721 100 9 18 0 1310817440 5883488 2130720
20:38:03 51242 8192000000 10231441072 10639900672 1324616064 13.918 100 36 69 2 1337745408 5935984 7193360
20:39:59 12828 8192000000 10261878736 10671083520 1351773840 14.163 100 54 108 1 1374529616 10907824 11847952
20:41:15 28491 8192000000 10268643776 10688274432 1362360848 14.259 100 40 79 1 1380522352 8185952 9975552
20:42:49 21661 8192000000 10297378704 10716229632 1381797936 14.433 100 51 102 4 1405499760 11979776 11722048
20:44:18 8977 8192000000 10311508784 10728284160 1390173648 14.507 100 50 100 1 1416340576 14486848 11680080
20:45:32 11477 8192000000 10312556672 10737700864 1395170768 14.552 100 54 107 1 1423009360 15922112 11916480
20:46:48 5425 8192000000 10336707184 10757550080 1411594192 14.698 100 68 135 1 1443275264 15638064 16043008
20:48:03 5232 8192000000 10338734608 10761277440 1412379600 14.705 100 56 111 2 1444708400 19447280 12881520
20:49:14 2227 8192000000 10358683856 10777358336 1420941936 14.781 100 76 152 2 1463266208 24705344 17618928
20:50:20 3914 8192000000 10345302800 10759733248 1402302784 14.615 100 77 154 0 1449721216 29044448 18373984
20:51:32 6165 8192000000 10354659456 10764263424 1407739760 14.664 100 72 144 3 1455745184 30308896 17696528
20:52:42 3719 8192000000 10351421280 10761453568 1396024512 14.560 100 77 149 3 1451590416 36975856 18590048
20:53:51 2736 8192000000 10356726064 10760232960 1395324144 14.553 100 84 163 1 1454186032 37975088 20886800
20:54:58 4268 8192000000 10331330272 10740084736 1374777504 14.370 100 65 127 4 1430206208 38870160 16558544
20:56:09 9557 8192000000 10332321632 10752282624 1392940016 14.532 100 41 80 0 1438603376 34841824 10821536
20:57:22 5534 8192000000 10332221616 10742210560 1375875920 14.380 99 57 114 0 1433367104 42625232 14865952
20:58:33 3318 8192000000 10349097072 10766508032 1396669648 14.565 100 53 106 1 1454236976 43720720 13846608
20:59:38 9166 8192000000 10332338592 10751299584 1367240176 14.302 99 44 88 0 1429418848 50497568 11681104
21:00:52 19251 8192000000 10304542016 10733817856 1361513200 14.251 100 26 52 1 1413400240 44734288 7152752
21:02:13 9618 8192000000 10335620896 10764234752 1373628224 14.360 100 32 63 2 1434732464 52411680 8692560
21:03:25 20745 8192000000 10306866976 10742370304 1344265984 14.096 98 35 70 2 1412948112 58127712 10554416
21:04:57 21267 8192000000 10307562496 10754260992 1351114800 14.158 99 24 48 1 1403346224 45827200 6404224
21:06:20 28621 8192000000 10284690096 10717147136 1345314816 14.105 100 40 80 1 1390496352 35549408 9632128
21:07:50 79505 8192000000 10274102256 10713071616 1335404640 14.016 98 15 30 1 1359581728 21259152 2917936
21:10:17 107473 8192000000 10272931888 10720542720 1320283120 13.879 96 20 38 3 1337145728 13035936 3826672
21:14:02 55134 8192000000 10321721296 10762133504 1381282544 14.428 99 23 47 1 1398735584 11725680 5727360
21:16:01 46289 8192000000 10321290704 10771357696 1404009760 14.631 100 17 34 1 1420247072 12646640 3590672
21:17:47 48053 8192000000 10319444352 10785390592 1402588992 14.618 100 13 26 1 1425199856 19542672 3068192
21:19:41 92718 8192000000 10319488832 10768814080 1375295984 14.374 98 10 20 0 1391853424 14420688 2136752
21:22:17 137609 8192000000 10312268944 10757668864 1364883648 14.281 98 25 50 0 1387907152 16302896 6720608
21:25:44 64035 8192000000 10336915952 10771329024 1393766704 14.539 99 43 85 2 1415808208 11407104 10634400
21:27:58 51582 8192000000 10316759136 10769895424 1383229792 14.445 98 21 42 1 1403129056 14842656 5056608
21:29:50 46518 8192000000 10293759392 10761682944 1355746832 14.199 97 15 30 0 1378313840 18694560 3872448
21:31:56 44437 8192000000 10283699248 10739240960 1368940688 14.318 98 6 12 0 1377649568 7371424 1337456
21:33:41 39278 8192000000 10292662416 10750902272 1384238864 14.454 99 17 33 0 1395965152 8071744 3654544
21:35:35 80601 8192000000 10233540432 10692558848 1296218736 13.661 94 17 34 0 1309570656 9344304 4007616
21:38:44 41200 8192000000 10245387408 10695749632 1327253520 13.942 97 14 28 2 1338086848 7784816 3048512
21:40:26 37593 8192000000 10242333232 10686455808 1329160320 13.960 98 36 73 0 1344217280 6573296 8483664
21:42:03 49053 8192000000 10241216640 10684248064 1316039024 13.841 98 20 39 1 1327185472 7283376 3863072
21:43:54 41063 8192000000 10247282512 10703687680 1320662624 13.883 98 13 26 0 1342856816 18909360 3284832
21:45:35 47362 8192000000 10247599184 10696130560 1298020544 13.677 96 37 74 0 1320431328 14555632 7855152
21:47:24 31693 8192000000 10264328080 10704609280 1340735008 14.064 99 19 38 1 1352819872 7858528 4226336
21:48:58 51102 8192000000 10261090192 10719318016 1356475264 14.206 100 15 30 0 1372493424 12736192 3281968
21:50:51 27813 8192000000 10274509312 10712997888 1347008368 14.121 99 32 62 1 1367438992 13508096 6922528
21:52:20 52502 8192000000 10261356224 10717425664 1355276432 14.195 100 14 28 0 1372103536 13787504 3039600
21:54:13 81657 8192000000 10272265104 10735112192 1353824880 14.182 99 8 16 0 1371303344 15668816 1809648
21:56:35 121645 8192000000 10274788384 10730336256 1348818496 14.137 98 28 56 0 1363138048 7949552 6370000
21:59:37 97927 8192000000 10270524256 10744848384 1377995248 14.399 100 7 14 0 1393008432 13437824 1575360
22:02:15 54043 8192000000 10258678608 10716266496 1356187744 14.203 98 14 28 2 1368384608 9012880 3183984
22:04:17 80396 8192000000 10287206592 10756915200 1390122528 14.507 100 18 36 0 1409305680 14882720 4300432
22:07:14 57021 8192000000 10287456448 10743021568 1368326560 14.312 99 38 74 0 1386442208 9694128 8421520
22:09:19 54463 8192000000 10290431952 10756849664 1386970352 14.479 99 23 46 1 1403707680 11477600 5259728
22:11:13 39394 8192000000 10287185200 10741420032 1365149376 14.284 98 23 46 0 1378951504 8757824 5044304
22:12:55 26487 8192000000 10323039664 10776576000 1403438352 14.626 100 21 42 0 1428856256 20551888 4866016
22:14:21 87151 8192000000 10279991200 10734854144 1342582624 14.081 97 8 16 1 1373977408 29554176 1840608
22:16:49 46482 8192000000 10297266544 10755592192 1350423248 14.151 97 23 46 0 1367593488 12221328 4948912
22:18:40 49172 8192000000 10292417776 10736746496 1334554512 14.008 96 18 36 0 1348369136 9645280 4169344
22:20:29 106611 8192000000 10273140848 10727747584 1353912448 14.183 98 28 56 0 1375249232 14569200 6767584
22:23:26 26003 8192000000 10273874224 10728292352 1352965792 14.174 98 26 52 0 1368189040 10076080 5147168
22:25:01 67989 8192000000 10253574608 10707415040 1324127520 13.914 97 13 26 1 1334934960 7604096 3203344
22:27:16 41626 8192000000 10243927744 10703380480 1320485328 13.881 97 16 32 0 1333527856 9414368 3628160
22:29:03 31874 8192000000 10236865248 10689085440 1308845104 13.776 97 20 40 1 1320963856 7129488 4989264
22:30:46 40315 8192000000 10253258672 10705612800 1339626656 14.054 99 18 36 0 1357453456 13594896 4231904
22:32:42 80108 8192000000 10262820112 10707914752 1330888592 13.975 98 15 30 0 1348851360 14366912 3595856
22:35:20 79969 8192000000 10264725504 10729623552 1344781312 14.100 99 24 48 1 1370219856 19960768 5477776
22:37:41 26500 8192000000 9373783328 9668608000 522087904 5.991 39 20 40 1 530397664 4181824 4127936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2022 22:39:01 to 03-01-2022 22:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 88 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2022 22:39:17 to 03-01-2022 22:39: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          253619
MON$OLDEST_ACTIVE               253620
MON$OLDEST_SNAPSHOT             253620
MON$NEXT_TRANSACTION            253620
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                       2053024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:376: 
:377: mscs-test-161.budzdorov.ru    Mon Jan  3 22:38:10 2022
:378:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:379: 
--- end of diff output ---

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

03.01.22 22:39: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-01-03 22:39:22 No severe PSQL-related problems occured
03.01.22 22:39:24. End of report.