Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15819396 kB
MemAvailable 30719000 kB
Buffers 0 kB
Cached 15849440 kB
SwapCached 4708 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-10-01 22:37:53
DTS_END                         2021-10-01 22:37:53.1400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38162
TRN_ID                          243870
ATT_ID                          688
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: 9606.70 17592 1152804
customer order: creation 182.48 373 21898
customer order: refuse 15.32 390 1838
order to supplier: creation 48.69 1259 5843
order to supplier: removal 3.60 109380 432
invoice (draft): creation 47.95 3377 5754
invoice (draft): removal 53.47 846 6416
invoice accept: apply 44.23 18741 5307
invoice accept: cancel 48.32 926 5798
customer reserve: creation 4140.73 182 496888
customer reserve: removal 4709.43 66 565132
realization accept: apply 88.81 177 10657
realization accept: cancel 86.23 124 10348
payment from customer: creation 63.13 125 7575
payment from customer: removal 6.24 96 749
payment to supplier: creation 51.73 137 6207
payment to supplier: removal 3.57 82 428
service: total inventory turnovers 5.12 1067 614
service: total monetary turnovers 6.83 35 819
service: aggregate perf. data 0.81 21500 97
service: refresh index statistics 0.03 192960 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2021 22:38:49 to 01-10-2021 22:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2017
TEST_TIME 2 3943
TEST_TIME 3 4600
TEST_TIME 4 5077
TEST_TIME 5 5700
TEST_TIME 6 5086
TEST_TIME 7 5144
TEST_TIME 8 5293
TEST_TIME 9 5397
TEST_TIME 10 5368
TEST_TIME 11 5871
TEST_TIME 12 6204
TEST_TIME 13 6464
TEST_TIME 14 6853
TEST_TIME 15 6788
TEST_TIME 16 7002
TEST_TIME 17 7473
TEST_TIME 18 7404
TEST_TIME 19 8244
TEST_TIME 20 8265
TEST_TIME 21 8411
TEST_TIME 22 8457
TEST_TIME 23 9110
TEST_TIME 24 9144
TEST_TIME 25 9201
TEST_TIME 26 9108
TEST_TIME 27 9132
TEST_TIME 28 9200
TEST_TIME 29 10014
TEST_TIME 30 11064
TEST_TIME 31 10885
TEST_TIME 32 10944
TEST_TIME 33 10942
TEST_TIME 34 10906
TEST_TIME 35 10854
TEST_TIME 36 10787
TEST_TIME 37 10658
TEST_TIME 38 10468
TEST_TIME 39 10317
TEST_TIME 40 10461
TEST_TIME 41 10455
TEST_TIME 42 10435
TEST_TIME 43 10427
TEST_TIME 44 10357
TEST_TIME 45 10192
TEST_TIME 46 10187
TEST_TIME 47 10137
TEST_TIME 48 10121
TEST_TIME 49 10119
TEST_TIME 50 10093
TEST_TIME 51 10001
TEST_TIME 52 9911
TEST_TIME 53 10000
TEST_TIME 54 10063
TEST_TIME 55 10023
TEST_TIME 56 9987
TEST_TIME 57 9912
TEST_TIME 58 9846
TEST_TIME 59 9841
TEST_TIME 60 9741
TEST_TIME 61 9630
TEST_TIME 62 9602
TEST_TIME 63 9595
TEST_TIME 64 9589
TEST_TIME 65 9648
TEST_TIME 66 9633
TEST_TIME 67 9674
TEST_TIME 68 9743
TEST_TIME 69 9786
TEST_TIME 70 9746
TEST_TIME 71 9844
TEST_TIME 72 9836
TEST_TIME 73 9789
TEST_TIME 74 9875
TEST_TIME 75 9889
TEST_TIME 76 9961
TEST_TIME 77 9856
TEST_TIME 78 9808
TEST_TIME 79 9764
TEST_TIME 80 9780
TEST_TIME 81 9731
TEST_TIME 82 9673
TEST_TIME 83 9660
TEST_TIME 84 9604
TEST_TIME 85 9512
TEST_TIME 86 9554
TEST_TIME 87 9571
TEST_TIME 88 9609
TEST_TIME 89 9593
TEST_TIME 90 9575
TEST_TIME 91 9617
TEST_TIME 92 9644
TEST_TIME 93 9624
TEST_TIME 94 9695
TEST_TIME 95 9687
TEST_TIME 96 9713
TEST_TIME 97 9834
TEST_TIME 98 9867
TEST_TIME 99 9797
TEST_TIME 100 9886
TEST_TIME 101 9818
TEST_TIME 102 9759
TEST_TIME 103 9723
TEST_TIME 104 9666
TEST_TIME 105 9725
TEST_TIME 106 9678
TEST_TIME 107 9641
TEST_TIME 108 9612
TEST_TIME 109 9535
TEST_TIME 110 9567
TEST_TIME 111 9548
TEST_TIME 112 9556
TEST_TIME 113 9567
TEST_TIME 114 9564
TEST_TIME 115 9594
TEST_TIME 116 9585
TEST_TIME 117 9635
TEST_TIME 118 9623
TEST_TIME 119 9606
TEST_TIME 120 9611

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2021 22:38:53 to 01-10-2021 22:38:56.

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 1611669 1611669 0 0.00 0 15519 7 0 0
doc_list_biud 1611669 1611669 0 0.00 0 25088 29 0 0
sp_add_invoice_to_stock 5399 5307 92 1.70 39 168334 18741 0 92
sp_cancel_adding_invoice 5798 5798 0 0.00 4 67008 927 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 17613 391 0 0
sp_cancel_customer_reserve 565132 565132 0 0.00 1 21059 66 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 3 9452 96 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 2 3641 82 0 0
sp_cancel_supplier_invoice 6425 6416 9 0.14 1 18665 847 0 9
sp_cancel_supplier_order 432 432 0 0.00 4 1006850 109381 0 0
sp_cancel_write_off 10348 10348 0 0.00 1 8817 124 0 0
sp_client_order 21898 21898 0 0.00 3 22256 373 0 0
sp_customer_reserve 496888 496888 0 0.00 0 54770 183 0 0
sp_fill_shopping_cart 33481 33481 0 0.00 1 41130 40 0 0
sp_fill_shopping_cart_clo_res 496881 496881 0 0.00 0 13675 15 0 0
sp_get_clo_for_invoice 5292 5292 0 0.00 0 26153 312 0 0
sp_kill_cost_storno 17323 17323 0 0.00 0 7824 69 0 0
sp_kill_qstorno_ret_qs2qd 1147460 1147460 0 0.00 0 25084 35 0 0
sp_kill_qty_storno 605911 605911 0 0.00 0 25087 76 0 0
sp_lock_selected_doc 22133 22133 0 0.00 0 8378 5 0 0
sp_make_cost_storno 29729 29729 0 0.00 0 14730 53 0 0
sp_multiply_rows_for_pdistr 29729 29729 0 0.00 0 5954 28 0 0
sp_multiply_rows_for_qdistr 21898 21898 0 0.00 0 18592 158 0 0
sp_pay_from_customer 7575 7575 0 0.00 4 12153 125 0 0
sp_pay_to_supplier 6207 6207 0 0.00 4 15368 138 0 0
sp_payment_common 13782 13782 0 0.00 1 12144 33 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 15545 353 0 0
sp_qd_handle_on_invoice_upd_sts 11088 11088 0 0.00 0 20262 326 0 0
sp_qd_handle_on_reserve_upd_sts 10657 10657 0 0.00 0 10438 60 0 0
sp_reserve_write_off 10657 10657 0 0.00 6 18069 178 0 0
sp_supplier_invoice 5754 5754 0 0.00 7 113888 3378 0 0
sp_supplier_order 5843 5843 0 0.00 6 33718 1260 0 0
srv_aggregate_perf_data 98 97 1 1.02 2583 218029 21500 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 1329 111383 37822 0 0
srv_find_qd_qs_mism 1101320 1101320 0 0.00 0 15518 6 0 0
srv_make_invnt_saldo 614 614 0 0.00 56 54339 1067 0 0
srv_make_money_saldo 819 819 0 0.00 0 5417 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 135907 292853 192961 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 5307 5307 0 0.00 0 18505 179 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 6113 132 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 1475 10 0 0
t$perf-norm:sp_cancel_customer_reserve 751 751 0 0.00 0 2122 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 1956 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 417 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 627 12 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 26495 1672 0 0
t$perf-norm:sp_cancel_write_off 1129 1129 0 0.00 0 8763 18 0 0
t$perf-norm:sp_client_order 21898 21898 0 0.00 0 8675 16 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 267 3 0 0
t$perf-norm:sp_pay_from_customer 7575 7575 0 0.00 0 9802 12 0 0
t$perf-norm:sp_pay_to_supplier 6207 6207 0 0.00 0 2297 8 0 0
t$perf-norm:sp_reserve_write_off 10657 10657 0 0.00 0 7050 11 0 0
t$perf-norm:sp_supplier_invoice 5754 5754 0 0.00 0 6717 16 0 0
t$perf-norm:sp_supplier_order 5843 5843 0 0.00 0 5339 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1148 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 750 13 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 1012 21 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 1828 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 118 33 0 0
v_add_invoice_to_stock 10760 10576 184 1.71 0 16579 18 0 184
v_all_customers 39806 39806 0 0.00 0 27 1 0 0
v_all_suppliers 32224 32224 0 0.00 0 29 1 0 0
v_all_wares 43796 43796 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 2963 16 0 0
v_cancel_client_order 3676 3676 0 0.00 0 17084 11 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 9439 14 0 0
v_cancel_customer_reserve 1502 1502 0 0.00 0 2996 9 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 3606 16 0 0
v_cancel_supplier_invoice 846 828 18 2.12 0 1991 18 0 18
v_cancel_supplier_order 864 864 0 0.00 0 3329 11 0 0
v_cancel_write_off 2258 2258 0 0.00 0 2845 15 0 0
v_max_id_clo_ord 5843 5843 0 0.00 0 669 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1359 8 0 0
v_max_id_ord_sup 5740 5740 0 0.00 0 3920 4 0 0
v_max_non_paid_invoice 1678 1678 0 0.00 0 866 3 0 0
v_max_non_paid_realizn 4091 4091 0 0.00 0 6188 7 0 0
v_min_id_clo_ord 5843 5843 0 0.00 0 1830 5 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 1487 5 0 0
v_min_id_ord_sup 5740 5740 0 0.00 0 13484 11 0 0
v_min_non_paid_invoice 6207 6207 0 0.00 0 5131 16 0 0
v_min_non_paid_realizn 7575 7575 0 0.00 0 12139 24 0 0
v_our_firm 10958 10958 0 0.00 0 16 1 0 0
v_reserve_write_off 21314 21314 0 0.00 0 17988 8 0 0
x_lock_depdocs_on_canc_invoice 11800 11800 0 0.00 0 19448 133 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 23862 429 0 0
x_make_qstorno_1000_1200 5843 5843 0 0.00 1 33711 1181 0 0
x_make_qstorno_1200_2000 5754 5754 0 0.00 3 90301 3224 0 0
x_make_qstorno_2100_3300 496888 496888 0 0.00 0 54749 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2021 22:38:57 to 01-10-2021 22:39:01.

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:07:56 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:58 21887 8192000000 10366716944 10690605056 1421145984 14.783 100 85 169 0 1454555520 5494560 27914976
20:10:26 11847 8192000000 10435099328 10762485760 1490237056 15.391 100 91 182 1 1526990096 7684656 29068384
20:11:50 18135 8192000000 10483615040 10827382784 1541133744 15.833 100 83 166 0 1575878000 9165152 25579104
20:13:16 6873 8192000000 10570738944 10930192384 1634118800 16.630 100 78 156 0 1667256384 9943504 23194080
20:14:27 6066 8192000000 10643075920 11011907584 1701223776 17.195 100 78 156 0 1735019824 11518144 22277904
20:15:36 5974 8192000000 10703513296 11082158080 1765232560 17.728 100 79 157 1 1799025744 11998656 21794528
20:16:44 2265 8192000000 10751871040 11133280256 1808287328 18.082 100 90 177 2 1846910992 14362656 24261008
20:17:48 5143 8192000000 10787397632 11171139584 1839405440 18.336 100 83 167 0 1876762896 14659648 22697808
20:18:55 1456 8192000000 10823071600 11213557760 1877205936 18.643 100 89 178 1 1913882208 11659344 25016928
20:19:57 19191 8192000000 10825529952 11224842240 1890936144 18.753 100 67 132 2 1920754768 11324192 18494432
20:21:18 10976 8192000000 10804951616 11224403968 1891776128 18.760 100 35 70 0 1908558576 6114448 10668000
20:22:31 3533 8192000000 10826393872 11244183552 1904565568 18.863 100 48 96 0 1925791376 7480224 13745584
20:23:39 5379 8192000000 10817807872 11249360896 1906912928 18.882 100 25 51 1 1920345424 5620368 7812128
20:24:45 7453 8192000000 10824960592 11257761792 1917674336 18.968 100 24 48 4 1931083808 6402416 7007056
20:25:53 45132 8192000000 10860227280 11278430208 1927637968 19.048 100 59 117 3 1955993104 10301088 18054048
20:27:38 4193 8192000000 10830109040 11255488512 1910200544 18.908 100 45 91 2 1931068032 7375888 13491600
20:28:43 10600 8192000000 10771316992 11208712192 1872801040 18.607 100 28 56 0 1886743744 5947184 7995520
20:29:55 8338 8192000000 10785332608 11175071744 1827811728 18.241 98 81 163 2 1858511392 5570064 25129600
20:31:04 6917 8192000000 10768544512 11175190528 1805872240 18.062 98 46 95 3 1826306192 7725776 12708176
20:32:13 5927 8192000000 10709717456 11117654016 1769292176 17.761 98 30 60 1 1783362624 5167568 8902880
20:33:24 43963 8192000000 10711687696 11135680512 1747217712 17.579 96 25 48 3 1759327936 5304576 6805648
20:35:11 5430 8192000000 10760627808 11190984704 1850890640 18.429 100 29 58 1 1866507104 6323648 9292816
20:36:17 5841 8192000000 10782736336 11213848576 1870349088 18.587 100 35 70 1 1887525632 6467456 10709088
20:37:25 51344 8192000000 10835745648 11253587968 1899025216 18.818 100 38 74 0 1915887952 6666416 10196320
20:39:24 2282 8192000000 10868142064 11278413824 1923206704 19.013 100 69 138 1 1952937312 10001136 19729472
20:40:32 28602 8192000000 10867233904 11299991552 1936712000 19.121 100 41 82 0 1963035664 13939536 12384128
20:42:17 8473 8192000000 10889719680 11331223552 1960311328 19.309 100 39 75 5 1987368880 16086992 10970560
20:43:31 12509 8192000000 10933107120 11352748032 1972012448 19.401 100 47 94 0 2004920800 19110544 13797808
20:44:50 28384 8192000000 10928776144 11367837696 1985351312 19.507 100 34 68 0 2018215536 22939008 9925216
20:46:25 5551 8192000000 10951903552 11379699712 1978754272 19.455 99 58 116 1 2023674416 26854224 18065920
20:47:35 9108 8192000000 10977034688 11408842752 2015450928 19.744 100 56 112 1 2065485104 32086816 17947360
20:48:54 3966 8192000000 10985978224 11410325504 1991319024 19.554 98 78 152 2 2048078192 33240080 23519088
20:50:02 16838 8192000000 10947196896 11387736064 1989082528 19.537 100 52 103 1 2037703056 32283024 16337504
20:51:30 1799 8192000000 10977411328 11393110016 1998134816 19.608 100 78 155 0 2057692144 36741808 22815520
20:52:39 6791 8192000000 10965433392 11399553024 1994503632 19.579 100 54 108 1 2052101456 40636864 16960960
20:53:52 5562 8192000000 10951004032 11375583232 1965246544 19.348 99 80 159 3 2033807680 43211392 25349744
20:55:05 4971 8192000000 10967922992 11386277888 1965203248 19.347 99 81 162 2 2036170240 45636768 25330224
20:56:15 1327 8192000000 10988814160 11403595776 1977698112 19.446 99 83 167 0 2057963680 54097616 26167952
20:57:20 3720 8192000000 10988165376 11415707648 2000488592 19.627 100 52 106 1 2065754656 47945040 17321024
20:58:30 8991 8192000000 10994303152 11420643328 2004523824 19.658 100 75 149 5 2080010064 51658704 23827536
20:59:45 1428 8192000000 11033031808 11447877632 2021043424 19.788 100 91 179 1 2109985440 59238432 29703584
21:00:52 14465 8192000000 10981746560 11423617024 2006121072 19.671 100 42 83 1 2064967008 45350032 13495904
21:02:13 10940 8192000000 10970223328 11421294592 1997328784 19.602 100 31 62 1 2058299408 50479984 10490640
21:03:41 12578 8192000000 10971871984 11417587712 1988566576 19.532 100 44 87 1 2059653456 56152928 14933952
21:04:56 15099 8192000000 10943139760 11393302528 1951583168 19.239 99 29 58 1 2018203184 56245072 10374944
21:06:15 25707 8192000000 10941328368 11417604096 1961240672 19.316 100 22 44 1 2036516928 66614288 8661968
21:08:02 54410 8192000000 10908080960 11359002624 1970194816 19.387 100 25 48 2 1989085904 12296672 6594416
21:10:05 59150 8192000000 10892202656 11353866240 1896257152 18.796 96 5 10 0 1910260528 13024752 978624
21:12:08 24316 8192000000 10899106320 11336273920 1939314832 19.141 98 28 54 1 1956970160 9929536 7725792
21:14:50 21203 8192000000 10884847376 11341230080 1947374000 19.206 100 31 58 2 1968004336 12828592 7801744
21:16:13 60931 8192000000 10865174144 11322699776 1911942736 18.922 98 44 88 0 1943300480 18339888 13017856
21:18:16 56977 8192000000 10875148112 11324895232 1904337536 18.861 98 24 47 2 1925080672 13148224 7594912
21:20:15 62383 8192000000 10862215808 11334225920 1915938608 18.954 98 30 63 0 1936669312 12988896 7741808
21:22:18 36593 8192000000 10895865264 11351719936 1961721216 19.320 100 21 42 1 1978421296 10199824 6500256
21:23:55 18663 8192000000 10906661888 11373031424 1977150352 19.442 100 23 45 1 1998551296 14451024 6949920
21:25:15 18088 8192000000 10930502208 11379191808 1983881936 19.495 100 50 100 1 2015564368 15644848 16037584
21:26:39 43632 8192000000 10881736880 11353055232 1955968512 19.274 100 11 22 1 1975529776 15871232 3690032
21:28:36 19133 8192000000 10890631568 11366699008 1947646960 19.208 99 13 26 1 1965630832 14121392 3862480
21:30:08 44846 8192000000 10898652608 11371241472 1967546160 19.366 100 19 37 1 1990942496 18044272 5352064
21:32:09 78785 8192000000 10889009216 11351986176 1951661696 19.240 99 32 64 2 1971952112 10773456 9516960
21:34:33 30823 8192000000 10874215408 11336884224 1945364048 19.190 100 17 32 0 1957172000 7481216 4326736
21:36:04 39909 8192000000 10846415888 11329900544 1916370192 18.958 99 11 22 0 1928163648 8569408 3224048
21:37:52 75226 8192000000 10844547664 11316453376 1913029200 18.931 98 12 24 0 1921056832 5289312 2738320
21:40:21 32042 8192000000 10870211056 11344302080 1921756400 19.001 98 6 12 0 1929943024 6945088 1241536
21:41:53 29354 8192000000 10860024352 11321942016 1859485504 18.499 95 30 59 1 1878465232 10223376 8756352
21:43:53 27779 8192000000 10883634368 11342888960 1902162000 18.844 97 37 74 2 1923048640 10544992 10341648
21:45:24 39525 8192000000 10880091072 11349823488 1929668912 19.064 99 21 43 1 1949735216 14067632 5998672
21:47:06 42872 8192000000 10881365632 11331145728 1873608672 18.613 96 36 72 1 1892193344 8116400 10468272
21:48:52 52193 8192000000 10861271168 11324514304 1849243280 18.416 95 12 24 0 1865282720 12591488 3447952
21:50:47 42302 8192000000 10857652624 11339431936 1920287424 18.989 99 26 52 1 1942653824 15239968 7126432
21:52:30 60865 8192000000 10882221744 11358175232 1929133024 19.060 99 9 18 0 1947027456 15309520 2584912
21:54:39 101154 8192000000 10891592896 11348254720 1911274128 18.917 97 28 56 1 1932329792 13204000 7851664
21:57:22 31153 8192000000 10866015408 11352518656 1948404640 19.214 100 11 22 1 1966026288 14815424 2806224
21:58:59 48218 8192000000 10852392208 11326967808 1924920432 19.026 99 7 14 0 1934038240 7177056 1940752
22:00:49 58637 8192000000 10884841584 11353939968 1951768528 19.241 100 14 28 0 1966481072 10568640 4143904
22:02:48 74874 8192000000 10891091552 11377225728 1965258416 19.348 100 16 32 0 1990195728 19676272 5261040
22:05:09 75591 8192000000 10891708768 11359985664 1928196784 19.052 98 33 65 2 1946263776 8426880 9640112
22:07:41 46345 8192000000 10884302560 11356831744 1917043056 18.963 97 25 50 1 1935559248 11331552 7184640
22:09:33 42795 8192000000 10911612768 11375898624 1961432224 19.317 99 33 64 3 1980580320 10754480 8393616
22:11:16 59232 8192000000 10882230496 11362390016 1887822256 18.728 95 33 66 1 1909941360 13050448 9068656
22:13:16 91230 8192000000 10905295888 11400351744 1942481568 19.167 98 12 24 1 1969831520 23653280 3696672
22:15:51 65012 8192000000 10933556288 11389702144 1970821328 19.392 99 31 62 1 1991129968 11672464 8636176
22:17:59 96183 8192000000 10906444832 11375308800 1962540800 19.326 99 33 66 0 1984510080 12526496 9442784
22:21:12 13652 8192000000 10925732304 11374583808 1977262304 19.443 100 62 127 1 2005604112 11296752 17045056
22:22:27 58578 8192000000 10855766096 11343626240 1914394016 18.942 97 14 28 1 1927226736 9043232 3789488
22:24:26 27365 8192000000 10874008752 11344093184 1928446416 19.054 98 31 63 1 1944343648 6492944 9404288
22:25:56 81549 8192000000 10876110208 11360051200 1955665184 19.272 100 10 20 0 1970599088 11792720 3141184
22:29:11 35450 8192000000 10874181776 11347955712 1889182512 18.739 96 25 50 0 1904795200 7897120 7715568
22:30:47 36426 8192000000 10906574176 11369500672 1939470224 19.143 98 32 65 0 1957701872 8515600 9716048
22:32:23 52908 8192000000 10866153456 11336671232 1890389536 18.749 96 21 43 0 1909013152 12662496 5961120
22:34:19 111382 8192000000 10893794672 11355357184 1851733840 18.436 94 12 24 0 1868323456 13535984 3053632
22:37:12 49475 8192000000 10060126144 10449788928 1155629616 12.362 59 24 45 1 1177919552 15662528 6627408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 92 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:01, from 01-10-2021 22:39:20 to 01-10-2021 22:39:21.

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          244878
MON$OLDEST_ACTIVE               244879
MON$OLDEST_SNAPSHOT             244879
MON$NEXT_TRANSACTION            244879
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                       2048288
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Fri Oct  1 22:38:07 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Fri Oct  1 22:38:19 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Fri Oct  1 22:38:19 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Fri Oct  1 22:38:23 2021
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
--- end of diff output ---

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

01.10.21 22:39:22. 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-10-01 22:39:25 No severe PSQL-related problems occured
01.10.21 22:39:26. End of report.