Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.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 16928584 kB
MemAvailable 31481348 kB
Buffers 0 kB
Cached 14741852 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-12-05 19:37:55
DTS_END                         2021-12-05 19:37:55.7820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51440
TRN_ID                          236796
ATT_ID                          655
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: 8803.46 25397 1056415
customer order: creation 165.98 377 19918
customer order: refuse 13.90 325 1668
order to supplier: creation 44.28 1425 5313
order to supplier: removal 3.19 105374 383
invoice (draft): creation 44.78 3523 5374
invoice (draft): removal 48.63 764 5835
invoice accept: apply 41.73 17770 5007
invoice accept: cancel 44.14 840 5297
customer reserve: creation 3772.35 180 452682
customer reserve: removal 4334.37 62 520124
realization accept: apply 80.13 194 9616
realization accept: cancel 84.41 121 10129
payment from customer: creation 58.24 123 6989
payment from customer: removal 5.55 69 666
payment to supplier: creation 47.25 154 5670
payment to supplier: removal 3.18 65 382
service: total inventory turnovers 4.50 1083 540
service: total monetary turnovers 6.11 25 733
service: aggregate perf. data 0.72 24116 86
service: refresh index statistics 0.03 351348 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2021 19:39:16 to 05-12-2021 19:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3711
TEST_TIME 2 3988
TEST_TIME 3 3776
TEST_TIME 4 4603
TEST_TIME 5 4489
TEST_TIME 6 4548
TEST_TIME 7 4920
TEST_TIME 8 4666
TEST_TIME 9 4972
TEST_TIME 10 5189
TEST_TIME 11 5550
TEST_TIME 12 5663
TEST_TIME 13 5630
TEST_TIME 14 5942
TEST_TIME 15 6158
TEST_TIME 16 6826
TEST_TIME 17 6850
TEST_TIME 18 7110
TEST_TIME 19 7594
TEST_TIME 20 7978
TEST_TIME 21 8038
TEST_TIME 22 8454
TEST_TIME 23 8647
TEST_TIME 24 9385
TEST_TIME 25 9715
TEST_TIME 26 9809
TEST_TIME 27 10165
TEST_TIME 28 10235
TEST_TIME 29 10010
TEST_TIME 30 9843
TEST_TIME 31 9684
TEST_TIME 32 9622
TEST_TIME 33 9856
TEST_TIME 34 9610
TEST_TIME 35 9557
TEST_TIME 36 9412
TEST_TIME 37 9426
TEST_TIME 38 9308
TEST_TIME 39 9146
TEST_TIME 40 9154
TEST_TIME 41 9030
TEST_TIME 42 9003
TEST_TIME 43 8945
TEST_TIME 44 9025
TEST_TIME 45 8945
TEST_TIME 46 8907
TEST_TIME 47 8915
TEST_TIME 48 8919
TEST_TIME 49 8892
TEST_TIME 50 8931
TEST_TIME 51 8913
TEST_TIME 52 8971
TEST_TIME 53 8908
TEST_TIME 54 9008
TEST_TIME 55 9042
TEST_TIME 56 8967
TEST_TIME 57 8929
TEST_TIME 58 9097
TEST_TIME 59 9075
TEST_TIME 60 9137
TEST_TIME 61 9114
TEST_TIME 62 9043
TEST_TIME 63 8955
TEST_TIME 64 8905
TEST_TIME 65 8864
TEST_TIME 66 8950
TEST_TIME 67 8899
TEST_TIME 68 8828
TEST_TIME 69 8760
TEST_TIME 70 8658
TEST_TIME 71 8724
TEST_TIME 72 8802
TEST_TIME 73 8797
TEST_TIME 74 8861
TEST_TIME 75 8890
TEST_TIME 76 8900
TEST_TIME 77 8970
TEST_TIME 78 8972
TEST_TIME 79 8933
TEST_TIME 80 8952
TEST_TIME 81 9078
TEST_TIME 82 9050
TEST_TIME 83 9088
TEST_TIME 84 9115
TEST_TIME 85 9019
TEST_TIME 86 8935
TEST_TIME 87 8944
TEST_TIME 88 8909
TEST_TIME 89 8871
TEST_TIME 90 8813
TEST_TIME 91 8777
TEST_TIME 92 8796
TEST_TIME 93 8820
TEST_TIME 94 8787
TEST_TIME 95 8821
TEST_TIME 96 8840
TEST_TIME 97 8819
TEST_TIME 98 8907
TEST_TIME 99 8953
TEST_TIME 100 8971
TEST_TIME 101 8933
TEST_TIME 102 8966
TEST_TIME 103 8969
TEST_TIME 104 9001
TEST_TIME 105 8984
TEST_TIME 106 9028
TEST_TIME 107 9043
TEST_TIME 108 8973
TEST_TIME 109 8920
TEST_TIME 110 8901
TEST_TIME 111 8882
TEST_TIME 112 8876
TEST_TIME 113 8828
TEST_TIME 114 8804
TEST_TIME 115 8774
TEST_TIME 116 8774
TEST_TIME 117 8825
TEST_TIME 118 8806
TEST_TIME 119 8832
TEST_TIME 120 8807

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-12-2021 19:39:20 to 05-12-2021 19:39:23.

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 1476800 1476800 0 0.00 0 26710 7 0 0
doc_list_biud 1476792 1476792 0 0.00 0 26915 28 0 0
sp_add_invoice_to_stock 5095 5007 88 1.72 21 164927 17770 0 88
sp_cancel_adding_invoice 5297 5297 0 0.00 4 67899 841 0 0
sp_cancel_client_order 1668 1668 0 0.00 4 9466 326 0 0
sp_cancel_customer_reserve 520124 520124 0 0.00 1 26916 62 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 3379 70 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 2987 66 0 0
sp_cancel_supplier_invoice 5838 5835 3 0.05 2 14378 764 0 3
sp_cancel_supplier_order 383 383 0 0.00 5 977908 105374 0 0
sp_cancel_write_off 10129 10129 0 0.00 1 13546 122 0 0
sp_client_order 19918 19918 0 0.00 3 33165 378 0 0
sp_customer_reserve 452682 452682 0 0.00 0 59125 180 0 0
sp_fill_shopping_cart 30603 30603 0 0.00 1 15401 43 0 0
sp_fill_shopping_cart_clo_res 452667 452667 0 0.00 0 10060 13 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 21059 356 0 0
sp_kill_cost_storno 16474 16474 0 0.00 0 8646 56 0 0
sp_kill_qstorno_ret_qs2qd 1056595 1056595 0 0.00 0 23744 33 0 0
sp_kill_qty_storno 558044 558044 0 0.00 0 26915 72 0 0
sp_lock_selected_doc 20106 20106 0 0.00 0 6204 4 0 0
sp_make_cost_storno 27267 27267 0 0.00 0 26699 57 0 0
sp_multiply_rows_for_pdistr 27267 27267 0 0.00 0 11776 32 0 0
sp_multiply_rows_for_qdistr 19918 19918 0 0.00 0 9845 145 0 0
sp_pay_from_customer 6989 6989 0 0.00 4 19692 124 0 0
sp_pay_to_supplier 5670 5670 0 0.00 5 17726 155 0 0
sp_payment_common 12659 12659 0 0.00 2 19683 34 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 9434 303 0 0
sp_qd_handle_on_invoice_upd_sts 10289 10289 0 0.00 0 24771 337 0 0
sp_qd_handle_on_reserve_upd_sts 9616 9616 0 0.00 0 17343 72 0 0
sp_reserve_write_off 9616 9616 0 0.00 6 25042 195 0 0
sp_supplier_invoice 5374 5374 0 0.00 6 89236 3523 0 0
sp_supplier_order 5313 5313 0 0.00 5 43397 1425 0 0
srv_aggregate_perf_data 87 86 1 1.14 3296 405277 24116 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1229 139099 45872 0 0
srv_find_qd_qs_mism 1010572 1010572 0 0.00 0 25598 6 0 0
srv_make_invnt_saldo 540 540 0 0.00 46 36652 1083 0 0
srv_make_money_saldo 733 733 0 0.00 0 1443 26 0 0
srv_recalc_idx_stat 3 3 0 0.00 332132 363435 351349 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 3 3 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 5007 5007 0 0.00 0 19825 150 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 5414 89 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 6367 19 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 2878 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 5372 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 3569 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 548 9 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 28949 1380 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 6110 15 0 0
t$perf-norm:sp_client_order 19918 19918 0 0.00 0 18821 20 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 18018 21 0 0
t$perf-norm:sp_pay_from_customer 6989 6989 0 0.00 0 10760 14 0 0
t$perf-norm:sp_pay_to_supplier 5670 5670 0 0.00 0 10754 10 0 0
t$perf-norm:sp_reserve_write_off 9616 9616 0 0.00 0 8122 15 0 0
t$perf-norm:sp_supplier_invoice 5374 5374 0 0.00 0 16727 17 0 0
t$perf-norm:sp_supplier_order 5313 5313 0 0.00 0 5559 18 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 409 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 380 7 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 756 18 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 5423 21 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10154 9978 176 1.73 0 5035 18 0 176
v_all_customers 36952 36952 0 0.00 0 65 1 0 0
v_all_suppliers 29268 29268 0 0.00 0 26 1 0 0
v_all_wares 39835 39835 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 5069 12 0 0
v_cancel_client_order 3336 3336 0 0.00 0 2418 5 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 2624 8 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 5142 17 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 1335 5 0 0
v_cancel_supplier_invoice 750 744 6 0.80 0 5216 27 0 6
v_cancel_supplier_order 766 766 0 0.00 0 2437 11 0 0
v_cancel_write_off 1958 1958 0 0.00 0 5164 18 0 0
v_max_id_clo_ord 5313 5313 0 0.00 0 1599 1 0 0
v_max_id_clo_res 971 971 0 0.00 0 1664 13 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 5829 5 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 2137 4 0 0
v_max_non_paid_realizn 3490 3490 0 0.00 0 5956 8 0 0
v_min_id_clo_ord 5313 5313 0 0.00 0 3739 7 0 0
v_min_id_clo_res 971 971 0 0.00 0 3117 8 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 5817 7 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 17016 27 0 0
v_min_non_paid_realizn 6989 6989 0 0.00 0 19678 24 0 0
v_our_firm 9879 9879 0 0.00 0 16 1 0 0
v_reserve_write_off 19231 19231 0 0.00 0 5676 8 0 0
x_lock_depdocs_on_canc_invoice 10760 10760 0 0.00 0 15243 128 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 9426 382 0 0
x_make_qstorno_1000_1200 5313 5313 0 0.00 1 43379 1344 0 0
x_make_qstorno_1200_2000 5374 5374 0 0.00 3 86882 3361 0 0
x_make_qstorno_2100_3300 452682 452682 0 0.00 0 59124 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2021 19:39:24 to 05-12-2021 19:39:28.

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:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:00 32885 8192000000 9912620080 10227793920 990682848 10.788 100 82 164 0 1018623984 6377776 21563360
17:10:40 22958 8192000000 9963475904 10289942528 1042725488 11.291 100 91 182 0 1075116288 9049312 23341488
17:12:15 16034 8192000000 9986377888 10326204416 1075080752 11.601 100 81 162 0 1105371168 10047728 20242688
17:13:35 14508 8192000000 10049356336 10399342592 1128977072 12.112 100 76 152 0 1157201344 10114256 18110016
17:14:52 8042 8192000000 10102390096 10466447360 1188470848 12.669 100 74 148 0 1218157824 11840304 17846672
17:16:01 3804 8192000000 10148559360 10521325568 1231256864 13.066 100 79 158 0 1266211568 17163552 17791152
17:17:06 3558 8192000000 10171705088 10554482688 1263427696 13.361 100 80 161 2 1293973664 12111568 18434400
17:18:11 1363 8192000000 10204149968 10584092672 1285504256 13.563 100 91 183 3 1318910176 13136336 20269584
17:19:14 11214 8192000000 10216127776 10601512960 1301996720 13.713 100 84 169 1 1332567680 12140064 18430896
17:20:26 4147 8192000000 10217779168 10613587968 1311630528 13.801 100 66 133 1 1335502512 9109520 14762464
17:21:32 11379 8192000000 10195407472 10614571008 1315126640 13.833 100 21 41 1 1326194784 6593904 4474240
17:22:46 8229 8192000000 10207197664 10624253952 1322778672 13.902 100 38 74 1 1338349888 6694960 8876256
17:23:57 38898 8192000000 10214509488 10637058048 1324147520 13.914 100 39 77 0 1339150720 6454784 8548416
17:25:37 5850 8192000000 10217602464 10640932864 1328145136 13.950 100 22 44 0 1340136816 6207136 5784544
17:26:43 5089 8192000000 10222388304 10642092032 1324936400 13.921 100 36 70 3 1343829632 10314560 8578672
17:27:53 5088 8192000000 10214926512 10634670080 1322307408 13.898 100 26 49 3 1332771632 5596176 4868048
17:29:00 28239 8192000000 10157425744 10568822784 1186112432 12.647 91 33 65 2 1198963328 5338016 7512880
17:30:32 5574 8192000000 10164804368 10564313088 1249778928 13.236 98 28 56 1 1261666400 5848256 6039216
17:31:42 9070 8192000000 10148694720 10550972416 1244285824 13.186 99 30 60 1 1256637536 5284592 7067120
17:32:51 17159 8192000000 10180499680 10593054720 1289759984 13.602 100 22 45 1 1300256480 5258608 5237888
17:34:10 18426 8192000000 10182643392 10600898560 1296377440 13.662 100 20 38 1 1305762128 5668960 3715728
17:35:29 10118 8192000000 10189930784 10609262592 1296879360 13.667 99 26 52 1 1308911232 5568064 6463808
17:36:41 10713 8192000000 10212470240 10629115904 1317878208 13.857 100 22 44 1 1328295952 5263104 5154640
17:37:52 38132 8192000000 10241394672 10662047744 1340123792 14.059 100 31 59 3 1354493472 6222880 8146800
17:39:51 8015 8192000000 10261245216 10680684544 1355440256 14.196 100 55 111 1 1378467248 10301504 12725488
17:41:05 6310 8192000000 10288796928 10705653760 1375694096 14.378 100 58 116 0 1402251504 13029568 13527840
17:42:14 7532 8192000000 10299462512 10716598272 1385174848 14.463 100 55 111 1 1411393584 13991248 12227488
17:43:28 15827 8192000000 10324627584 10741813248 1395949104 14.559 100 61 121 2 1431720352 21754416 14016832
17:44:47 10955 8192000000 10306098816 10731171840 1388268304 14.490 99 43 84 2 1416033856 17749376 10016176
17:46:02 11237 8192000000 10331718000 10758135808 1399944080 14.594 99 47 94 1 1436489712 24824416 11721216
17:47:20 6395 8192000000 10338882960 10762006528 1409570112 14.680 100 56 109 1 1449385616 26190192 13625312
17:48:32 4045 8192000000 10347266672 10771935232 1412363568 14.705 100 59 118 2 1454967632 28719280 13884784
17:49:41 6207 8192000000 10347445344 10770538496 1391411040 14.518 98 65 129 1 1439691712 32579376 15701296
17:50:51 4849 8192000000 10346622080 10764599296 1396178144 14.561 99 61 123 0 1447497472 36317392 15001936
17:52:01 5056 8192000000 10374976400 10784227328 1417101648 14.747 100 83 166 0 1478685408 41245600 20338160
17:53:15 4316 8192000000 10347926032 10755256320 1383105552 14.444 100 86 172 0 1451016432 46420176 21490704
17:54:23 7350 8192000000 10329173968 10738491392 1361994288 14.255 99 66 132 1 1418456592 39566128 16896176
17:55:44 6508 8192000000 10352995616 10765758464 1390757328 14.513 100 73 146 2 1452857760 43695600 18404832
17:56:56 10527 8192000000 10327539152 10762108928 1388928528 14.496 100 39 78 0 1441941888 42513744 10499616
17:58:09 2728 8192000000 10318790400 10735267840 1359337920 14.231 99 57 112 0 1412669632 38466064 14865648
17:59:15 7251 8192000000 10298577840 10720133120 1353862144 14.182 100 43 86 1 1409741872 43814736 12064992
18:00:25 15232 8192000000 10312343504 10738036736 1360782240 14.244 100 40 80 0 1411983872 40449408 10752224
18:01:43 19320 8192000000 10298935232 10739625984 1345229440 14.105 98 25 49 1 1389350896 37701264 6420192
18:03:11 55259 8192000000 10285162224 10725232640 1346958672 14.120 99 20 40 2 1381944560 29461424 5524464
18:05:11 23233 8192000000 10254929280 10704384000 1341770416 14.073 99 21 42 0 1364181808 17580752 4830640
18:06:35 55408 8192000000 10287916704 10724147200 1366095680 14.292 100 35 61 1 1387191696 13761824 7334192
18:08:31 46047 8192000000 10258116192 10708750336 1329431056 13.962 97 12 24 3 1348015440 15852032 2732352
18:10:20 86177 8192000000 10283634944 10710482944 1356115648 14.202 99 25 48 2 1373035264 11820208 5099408
18:12:53 37942 8192000000 10301399088 10738446336 1382538528 14.439 100 40 75 2 1401653856 9940800 9174528
18:14:33 73949 8192000000 10277481584 10727702528 1366691808 14.297 99 17 35 0 1378339616 7628208 4019600
18:16:47 1228 8192000000 10299902880 10727059456 1378401328 14.402 100 70 140 2 1404812320 9761552 16649440
18:17:49 26235 8192000000 10306559344 10740809728 1369521744 14.323 98 63 126 1 1395558272 10824080 15212448
18:19:17 88114 8192000000 10307576384 10762465280 1398116256 14.578 100 15 30 0 1418729264 16568320 4044688
18:21:45 68763 8192000000 10295374416 10757361664 1398759632 14.584 100 8 16 0 1418245696 17542576 1943488
18:24:01 52248 8192000000 10287712000 10745376768 1380278640 14.419 99 11 22 1 1395014496 12193568 2542288
18:25:59 57004 8192000000 10284913648 10740830208 1374211200 14.365 99 19 38 1 1388496048 9895520 4389328
18:28:06 97534 8192000000 10279910240 10739707904 1340432080 14.061 96 9 18 1 1354112800 11388784 2291936
18:30:49 139099 8192000000 10273333664 10721218560 1302069088 13.714 94 17 34 0 1323998160 18087712 3841360
18:34:11 48515 8192000000 10315550896 10758217728 1391168208 14.516 100 36 72 0 1413376064 14536464 7671392
18:36:00 51817 8192000000 10308687616 10743222272 1340224560 14.059 96 35 71 0 1359118592 11256576 7637456
18:37:52 77979 8192000000 10279185328 10726203392 1299322144 13.689 94 11 22 0 1309565536 8287104 1956288
18:40:10 54977 8192000000 10269537456 10710355968 1331629184 13.982 97 18 36 1 1343909472 8660160 3620128
18:42:15 9746 8192000000 10284542896 10725314560 1379979840 14.416 100 23 45 1 1392905840 7551664 5374336
18:43:26 48531 8192000000 10271910224 10740060160 1370946944 14.336 99 15 30 2 1384401776 9636144 3818688
18:45:14 88248 8192000000 10276824432 10720399360 1297547040 13.673 94 12 24 0 1309794096 9225968 3021088
18:47:46 50529 8192000000 10276345264 10734821376 1362539744 14.260 99 16 32 0 1383964080 17484640 3939696
18:49:44 95610 8192000000 10284490640 10749599744 1362096272 14.256 99 15 30 1 1380784384 14897184 3790928
18:52:39 47347 8192000000 10304242976 10734616576 1351410896 14.160 98 30 59 2 1366023200 8316464 6295840
18:54:29 112409 8192000000 10280545072 10724614144 1280343408 13.516 93 9 19 0 1295812848 13521184 1948256
18:57:21 85510 8192000000 10269620048 10725273600 1308692368 13.774 95 18 36 0 1326437424 13829568 3915488
18:59:47 62469 8192000000 10280710096 10717728768 1349114896 14.140 98 30 60 1 1367543072 11994288 6433888
19:01:50 131808 8192000000 10283016832 10723438592 1371047216 14.336 99 25 50 0 1385463024 8896736 5519072
19:05:16 38187 8192000000 10270324320 10725543936 1375639280 14.378 100 8 15 1 1385404080 8346928 1417872
19:07:01 59873 8192000000 10263978512 10716307456 1345160608 14.104 98 10 20 0 1356768496 9450208 2157680
19:09:01 48104 8192000000 10272241328 10731978752 1366291968 14.294 99 10 20 0 1380519840 11803568 2424304
19:10:49 57867 8192000000 10291648448 10744946688 1387902720 14.487 100 16 31 1 1401834368 9943792 3987856
19:12:47 47265 8192000000 10272549488 10723831808 1353266336 14.177 98 18 36 1 1366861520 9247472 4347712
19:14:35 33880 8192000000 10307856672 10759970816 1394908096 14.550 100 24 48 1 1418043280 17714896 5420288
19:16:17 99439 8192000000 10289068496 10736885760 1356933888 14.210 98 13 26 1 1379543040 19646480 2962672
19:19:08 60390 8192000000 10299562400 10756857856 1381956944 14.434 100 22 45 0 1405996992 19381696 4658352
19:21:12 45762 8192000000 10285086928 10729017344 1350007168 14.148 98 20 40 0 1363699632 9200688 4491776
19:23:05 136045 8192000000 10278728960 10734813184 1326983408 13.940 96 23 46 1 1346255120 14220528 5051184
19:27:27 29000 8192000000 10261134704 10714451968 1363555824 14.269 100 8 16 2 1377505568 12329648 1620096
19:28:58 49132 8192000000 10265324704 10730704896 1376371568 14.384 100 13 26 0 1391094800 11841920 2881312
19:30:47 63820 8192000000 10284744112 10732072960 1343682016 14.091 97 22 44 1 1364531072 15466960 5382096
19:33:40 65989 8192000000 10262678976 10730156032 1336421088 14.025 97 9 18 0 1356235456 17347216 2467152
19:35:53 50881 8192000000 10255493056 10700595200 1308300848 13.771 96 24 48 0 1322469712 7749040 6419824
19:37:45 24490 8192000000 9220776016 9488134144 379353872 4.425 28 5 10 0 384995088 4754048 887168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-12-2021 19:39:30 to 05-12-2021 19:39:45.

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 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-12-2021 19:39:46 to 05-12-2021 19:39:48.

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          236656
MON$OLDEST_ACTIVE               238631
MON$OLDEST_SNAPSHOT             238631
MON$NEXT_TRANSACTION            238631
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                       2007360
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.33541 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33541 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33541 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 ---
:71: 
:72: mscs-test-161.budzdorov.ru    Sun Dec  5 19:38:15 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
--- end of diff output ---

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

05.12.21 19:39:49. 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-12-05 19:39:51 No severe PSQL-related problems occured
05.12.21 19:39:53. End of report.