Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.05.2022 22:40
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 16542016 kB
MemAvailable 31404648 kB
Buffers 0 kB
Cached 15209828 kB
SwapCached 2436 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.10
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-05-29 22:38:20
DTS_END                         2022-05-29 22:38:20.3870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35906
TRN_ID                          233603
ATT_ID                          623
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: 9076.22 19860 1089146
customer order: creation 164.98 388 19797
customer order: refuse 14.14 370 1697
order to supplier: creation 44.54 1327 5345
order to supplier: removal 3.65 99299 438
invoice (draft): creation 44.77 3466 5372
invoice (draft): removal 52.39 841 6287
invoice accept: apply 41.03 20148 4924
invoice accept: cancel 47.36 785 5683
customer reserve: creation 3725.89 205 447107
customer reserve: removal 4649.33 60 557919
realization accept: apply 78.83 176 9460
realization accept: cancel 84.19 127 10103
payment from customer: creation 57.13 108 6855
payment from customer: removal 5.43 80 651
payment to supplier: creation 47.44 113 5693
payment to supplier: removal 3.09 62 371
service: total inventory turnovers 5.03 977 603
service: total monetary turnovers 6.15 26 738
service: aggregate perf. data 0.83 20770 99
service: refresh index statistics 0.03 247869 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-05-2022 22:40:48 to 29-05-2022 22:40:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4887
TEST_TIME 2 5061
TEST_TIME 3 5354
TEST_TIME 4 5475
TEST_TIME 5 5503
TEST_TIME 6 5390
TEST_TIME 7 5181
TEST_TIME 8 5556
TEST_TIME 9 6099
TEST_TIME 10 6452
TEST_TIME 11 6410
TEST_TIME 12 6641
TEST_TIME 13 6602
TEST_TIME 14 6773
TEST_TIME 15 6823
TEST_TIME 16 7419
TEST_TIME 17 7671
TEST_TIME 18 8161
TEST_TIME 19 8348
TEST_TIME 20 8892
TEST_TIME 21 8786
TEST_TIME 22 8677
TEST_TIME 23 8739
TEST_TIME 24 9029
TEST_TIME 25 9190
TEST_TIME 26 9284
TEST_TIME 27 9509
TEST_TIME 28 9728
TEST_TIME 29 10142
TEST_TIME 30 10689
TEST_TIME 31 10555
TEST_TIME 32 10570
TEST_TIME 33 10425
TEST_TIME 34 10225
TEST_TIME 35 10105
TEST_TIME 36 10014
TEST_TIME 37 10076
TEST_TIME 38 9980
TEST_TIME 39 9801
TEST_TIME 40 9678
TEST_TIME 41 9572
TEST_TIME 42 9789
TEST_TIME 43 9716
TEST_TIME 44 9597
TEST_TIME 45 9536
TEST_TIME 46 9365
TEST_TIME 47 9511
TEST_TIME 48 9577
TEST_TIME 49 9462
TEST_TIME 50 9388
TEST_TIME 51 9353
TEST_TIME 52 9295
TEST_TIME 53 9281
TEST_TIME 54 9157
TEST_TIME 55 9086
TEST_TIME 56 9013
TEST_TIME 57 9115
TEST_TIME 58 9127
TEST_TIME 59 9121
TEST_TIME 60 9115
TEST_TIME 61 9148
TEST_TIME 62 9189
TEST_TIME 63 9182
TEST_TIME 64 9225
TEST_TIME 65 9273
TEST_TIME 66 9297
TEST_TIME 67 9291
TEST_TIME 68 9432
TEST_TIME 69 9426
TEST_TIME 70 9392
TEST_TIME 71 9515
TEST_TIME 72 9437
TEST_TIME 73 9319
TEST_TIME 74 9354
TEST_TIME 75 9260
TEST_TIME 76 9266
TEST_TIME 77 9241
TEST_TIME 78 9190
TEST_TIME 79 9242
TEST_TIME 80 9195
TEST_TIME 81 9166
TEST_TIME 82 9157
TEST_TIME 83 9142
TEST_TIME 84 9207
TEST_TIME 85 9204
TEST_TIME 86 9243
TEST_TIME 87 9224
TEST_TIME 88 9240
TEST_TIME 89 9254
TEST_TIME 90 9300
TEST_TIME 91 9265
TEST_TIME 92 9312
TEST_TIME 93 9331
TEST_TIME 94 9338
TEST_TIME 95 9265
TEST_TIME 96 9198
TEST_TIME 97 9176
TEST_TIME 98 9162
TEST_TIME 99 9112
TEST_TIME 100 9075
TEST_TIME 101 9032
TEST_TIME 102 9000
TEST_TIME 103 8995
TEST_TIME 104 9034
TEST_TIME 105 9038
TEST_TIME 106 9054
TEST_TIME 107 9047
TEST_TIME 108 9070
TEST_TIME 109 9171
TEST_TIME 110 9172
TEST_TIME 111 9190
TEST_TIME 112 9152
TEST_TIME 113 9225
TEST_TIME 114 9199
TEST_TIME 115 9192
TEST_TIME 116 9140
TEST_TIME 117 9131
TEST_TIME 118 9152
TEST_TIME 119 9147
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-05-2022 22:40:51 to 29-05-2022 22:40:55.

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 1504108 1504108 0 0.00 0 35925 7 0 0
doc_list_biud 1504108 1504108 0 0.00 0 36700 29 0 0
sp_add_invoice_to_stock 5048 4924 124 2.45 35 220787 20149 0 124
sp_cancel_adding_invoice 5683 5683 0 0.00 5 88889 786 0 0
sp_cancel_client_order 1697 1697 0 0.00 4 15666 370 0 0
sp_cancel_customer_reserve 557919 557919 0 0.00 1 29053 60 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 2 6875 80 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 2 2611 62 0 0
sp_cancel_supplier_invoice 6295 6287 8 0.12 1 36703 842 0 8
sp_cancel_supplier_order 438 438 0 0.00 6 891895 99299 0 0
sp_cancel_write_off 10103 10103 0 0.00 1 35926 128 0 0
sp_client_order 19797 19797 0 0.00 3 31667 388 0 0
sp_customer_reserve 447107 447107 0 0.00 0 63500 205 0 0
sp_fill_shopping_cart 30506 30506 0 0.00 1 12699 40 0 0
sp_fill_shopping_cart_clo_res 447097 447097 0 0.00 0 17495 16 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 17694 298 0 0
sp_kill_cost_storno 16808 16808 0 0.00 0 35924 62 0 0
sp_kill_qstorno_ret_qs2qd 1132666 1132666 0 0.00 0 36697 33 0 0
sp_kill_qty_storno 596501 596501 0 0.00 0 36699 71 0 0
sp_lock_selected_doc 20005 20005 0 0.00 0 6622 4 0 0
sp_make_cost_storno 26922 26922 0 0.00 0 12324 53 0 0
sp_multiply_rows_for_pdistr 26922 26922 0 0.00 0 8747 29 0 0
sp_multiply_rows_for_qdistr 19797 19797 0 0.00 0 30702 155 0 0
sp_pay_from_customer 6855 6855 0 0.00 4 11680 108 0 0
sp_pay_to_supplier 5693 5693 0 0.00 4 8570 114 0 0
sp_payment_common 12548 12548 0 0.00 1 10300 31 0 0
sp_qd_handle_on_cancel_clo 1697 1697 0 0.00 0 15644 340 0 0
sp_qd_handle_on_invoice_upd_sts 10597 10597 0 0.00 0 15396 342 0 0
sp_qd_handle_on_reserve_upd_sts 9460 9460 0 0.00 0 17924 62 0 0
sp_reserve_write_off 9460 9460 0 0.00 6 17938 177 0 0
sp_supplier_invoice 5372 5372 0 0.00 6 86343 3467 0 0
sp_supplier_order 5345 5345 0 0.00 5 39368 1328 0 0
srv_aggregate_perf_data 100 99 1 1.00 2633 254082 20771 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1634 111894 47447 0 0
srv_find_qd_qs_mism 1043525 1043525 0 0.00 0 16690 6 0 0
srv_make_invnt_saldo 603 603 0 0.00 37 40955 977 0 0
srv_make_money_saldo 738 738 0 0.00 1 808 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 163583 415468 247870 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 4925 4925 0 0.00 0 17080 188 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 4875 107 0 0
t$perf-norm:sp_cancel_client_order 1697 1697 0 0.00 0 5239 12 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1168 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 13092 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 2064 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1394 28 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 21617 1687 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 948 7 0 0
t$perf-norm:sp_client_order 19797 19797 0 0.00 0 17817 18 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 1315 6 0 0
t$perf-norm:sp_pay_from_customer 6855 6855 0 0.00 0 8996 18 0 0
t$perf-norm:sp_pay_to_supplier 5693 5693 0 0.00 0 2698 7 0 0
t$perf-norm:sp_reserve_write_off 9460 9460 0 0.00 0 10024 11 0 0
t$perf-norm:sp_supplier_invoice 5372 5372 0 0.00 0 8668 18 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 8887 21 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 238 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 2956 25 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 19730 49 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 57 17 0 0
v_add_invoice_to_stock 10074 9826 248 2.46 0 5665 15 0 248
v_all_customers 36342 36342 0 0.00 0 14 1 0 0
v_all_suppliers 29770 29770 0 0.00 0 14 1 0 0
v_all_wares 39594 39594 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 3602 13 0 0
v_cancel_client_order 3394 3394 0 0.00 0 2790 6 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 2789 6 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1602 7 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 1070 4 0 0
v_cancel_supplier_invoice 812 796 16 1.97 0 2113 15 0 16
v_cancel_supplier_order 876 876 0 0.00 0 2050 10 0 0
v_cancel_write_off 1966 1966 0 0.00 0 1944 14 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 3913 3 0 0
v_max_id_clo_res 938 938 0 0.00 0 3154 12 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 4697 5 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 3996 9 0 0
v_max_non_paid_realizn 3475 3475 0 0.00 0 4401 6 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 3671 6 0 0
v_min_id_clo_res 938 938 0 0.00 0 2905 8 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 6878 8 0 0
v_min_non_paid_invoice 5693 5693 0 0.00 0 7323 22 0 0
v_min_non_paid_realizn 6855 6855 0 0.00 0 10295 23 0 0
v_our_firm 10012 10012 0 0.00 0 12 1 0 0
v_reserve_write_off 18920 18920 0 0.00 0 2914 5 0 0
x_lock_depdocs_on_canc_invoice 11572 11572 0 0.00 0 14713 127 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 21552 450 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 1 39357 1245 0 0
x_make_qstorno_1200_2000 5372 5372 0 0.00 3 85154 3326 0 0
x_make_qstorno_2100_3300 447107 447107 0 0.00 0 63499 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-05-2022 22:40:56 to 29-05-2022 22:41:00.

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:24 9 8192000000 8800651568 8812593152 5305344 0.064 2 1 2 0 5404944 56816 42784
20:09:25 28357 8192000000 9909253872 10227085312 992204768 10.803 100 89 178 1 1022476784 6620656 23651360
20:10:57 17130 8192000000 9953052336 10279612416 1038869776 11.254 100 85 170 0 1069368800 8461632 22037392
20:12:22 8660 8192000000 9995280000 10329681920 1079261936 11.640 100 84 168 2 1109365728 9380672 20723120
20:13:33 14636 8192000000 10046481776 10396585984 1125310224 12.077 100 80 160 0 1155093840 11016848 18766768
20:14:52 8646 8192000000 10086754720 10447912960 1173204512 12.527 100 88 174 2 1205774176 12319792 20249872
20:16:04 5929 8192000000 10140098112 10509914112 1223226256 12.992 100 81 163 0 1253555840 12057360 18272224
20:17:13 3057 8192000000 10175834576 10551840768 1262632912 13.354 100 87 174 0 1295751968 13876992 19242064
20:18:17 2377 8192000000 10195765472 10577231872 1279378352 13.507 100 83 166 1 1311364016 13016480 18969184
20:19:23 2612 8192000000 10212164224 10594217984 1296272288 13.661 100 89 178 1 1328405984 11892880 20240816
20:20:27 18216 8192000000 10220899936 10617769984 1306601296 13.755 100 63 128 1 1332801728 11509840 14690592
20:21:46 7842 8192000000 10198134128 10615803904 1313176496 13.815 100 19 38 0 1324168768 7003456 3988816
20:22:58 11070 8192000000 10216805696 10628329472 1323322480 13.907 100 24 48 0 1339520080 11220080 4977520
20:24:12 9720 8192000000 10208093376 10630365184 1326637984 13.937 100 20 40 2 1337675440 6466160 4571296
20:25:23 65124 8192000000 10221080192 10645184512 1334572672 14.008 100 32 62 2 1346901504 5254304 7074528
20:27:30 4630 8192000000 10229679968 10647515136 1337370864 14.034 100 44 88 0 1354861968 7500624 9990480
20:28:37 5275 8192000000 10211997296 10629595136 1311910912 13.803 99 25 51 1 1323773952 5507696 6355344
20:29:44 6838 8192000000 10180191344 10593746944 1270760032 13.429 98 22 42 1 1286197264 10074144 5363088
20:30:51 21196 8192000000 10169662160 10578513920 1232136832 13.074 95 26 52 1 1243682720 5038608 6507280
20:32:13 5814 8192000000 10153691040 10554630144 1261266992 13.342 100 36 72 3 1277491856 7673008 8551856
20:33:20 4881 8192000000 10169713424 10566447104 1265849408 13.384 100 32 64 1 1278749520 5178576 7721536
20:34:28 6986 8192000000 10170069856 10582036480 1279218688 13.506 100 28 54 0 1291833296 5818816 6795792
20:35:40 20449 8192000000 10172226528 10586206208 1272025712 13.440 99 25 50 2 1283807824 6310960 5471152
20:37:02 9443 8192000000 10189919648 10603454464 1301176368 13.706 100 17 34 2 1310394592 5655008 3563216
20:38:13 6877 8192000000 10216983920 10631512064 1324790768 13.920 100 35 68 1 1337947264 5553616 7602880
20:40:00 54980 8192000000 10258090224 10669125632 1345573936 14.108 100 54 108 2 1369403776 11447616 12382224
20:42:01 27535 8192000000 10263667808 10687664128 1358801024 14.227 100 37 74 0 1376612736 9340912 8470800
20:43:49 12553 8192000000 10282710928 10708983808 1378102224 14.400 100 41 82 0 1396939872 9546640 9291008
20:45:08 11314 8192000000 10307610848 10728591360 1387798528 14.486 100 62 124 0 1417611664 15603568 14209568
20:46:24 14173 8192000000 10306992656 10732597248 1392279072 14.526 100 59 117 0 1420867920 13953760 14635088
20:47:44 12303 8192000000 10329219072 10751582208 1393116224 14.534 99 60 119 1 1423513744 15820208 14577312
20:49:00 8031 8192000000 10345914720 10771795968 1420272624 14.775 100 55 109 1 1455302640 21898944 13131072
20:50:12 3051 8192000000 10373409728 10790162432 1429858768 14.860 100 85 169 0 1479478624 28202464 21417392
20:51:20 11063 8192000000 10349183680 10775494656 1418747984 14.762 100 60 119 0 1461280336 26809408 15722944
20:52:38 4202 8192000000 10332675600 10758410240 1392443360 14.528 99 59 119 0 1440889648 33078736 15367552
20:53:46 7579 8192000000 10322771376 10736119808 1368469808 14.313 98 65 131 1 1418641360 33669120 16502432
20:54:56 1633 8192000000 10352407632 10760867840 1392604352 14.529 100 81 161 1 1452996768 40669408 19723008
20:56:02 3396 8192000000 10287826096 10698866688 1342481984 14.080 99 62 124 0 1394443248 36055792 15905472
20:57:14 10220 8192000000 10300734960 10719891456 1354992080 14.192 99 46 90 1 1400308016 33630032 11685904
20:58:33 2537 8192000000 10369947968 10775683072 1398118848 14.578 100 91 175 0 1465117488 46339904 20658736
20:59:38 33579 8192000000 10337703584 10770108416 1400976560 14.604 100 37 74 1 1451195040 40541136 9677344
21:01:26 8432 8192000000 10349768656 10773139456 1391597008 14.520 100 53 105 1 1456945664 51656112 13692544
21:02:37 26987 8192000000 10341948608 10783068160 1390732192 14.512 100 37 73 2 1455651280 54797696 10121392
21:04:09 23106 8192000000 10323053168 10771267584 1359009648 14.228 98 27 54 3 1414324336 47508480 7806208
21:06:23 24417 8192000000 10294597056 10735194112 1347062768 14.121 99 32 63 1 1403647808 47765312 8819728
21:08:15 42695 8192000000 10292056640 10716307456 1359929040 14.237 99 30 56 3 1379501408 13130896 6441472
21:10:02 12884 8192000000 10300984272 10733895680 1378107152 14.400 100 42 84 1 1396876800 9527072 9242576
21:11:16 111894 8192000000 10296873456 10745077760 1383951984 14.452 100 25 50 2 1399542928 9420592 6170352
21:14:13 28661 8192000000 10292678256 10741596160 1364200464 14.275 98 18 36 0 1379635776 11298544 4136768
21:16:00 50869 8192000000 10283887232 10747568128 1369222864 14.320 99 7 14 0 1381569120 10769248 1577008
21:18:30 65262 8192000000 10301897664 10758385664 1385799088 14.468 99 20 41 0 1409482080 18864576 4818416
21:20:36 42511 8192000000 10277241552 10731048960 1359467728 14.233 98 20 41 1 1374476336 10676512 4332096
21:22:20 85001 8192000000 10293769376 10747641856 1357988784 14.219 98 28 56 0 1384302592 20034832 6278976
21:24:46 101742 8192000000 10289407648 10744926208 1356277552 14.204 98 20 40 0 1373918816 12768768 4872496
21:27:40 7396 8192000000 10273122096 10739163136 1376440816 14.385 100 12 24 1 1389899728 10610032 2848880
21:28:48 43997 8192000000 10272772192 10737180672 1375150256 14.373 99 21 43 0 1389389488 9464736 4774496
21:30:39 64199 8192000000 10281385664 10743615488 1359962512 14.237 98 36 72 1 1381084512 12787296 8334704
21:32:45 81828 8192000000 10292130432 10757267456 1382996336 14.443 99 11 22 0 1395883968 9796928 3090704
21:35:19 34213 8192000000 10283633504 10743459840 1353106864 14.175 97 8 16 2 1363221264 8562608 1551792
21:37:06 53471 8192000000 10293902480 10737995776 1320565024 13.882 95 29 57 0 1335762896 8983360 6214512
21:39:00 20903 8192000000 10283064960 10722234368 1328648624 13.955 96 44 86 3 1347338672 9565968 9124080
21:40:25 48889 8192000000 10286569376 10737573888 1360327616 14.240 99 27 54 0 1376109264 9733120 6048528
21:42:17 23229 8192000000 10275707120 10719244288 1342134464 14.077 98 39 78 1 1361582832 11108752 8339616
21:43:45 85608 8192000000 10272613952 10722988032 1302117872 13.714 95 13 26 0 1319798640 14702704 2978064
21:46:13 93442 8192000000 10280504576 10735144960 1320572368 13.882 96 11 22 0 1340393024 17412112 2408544
21:48:53 51247 8192000000 10264368336 10719793152 1313836144 13.821 97 37 74 1 1336572224 14465072 8271008
21:50:46 90443 8192000000 10260973536 10703519744 1312164512 13.806 97 22 44 0 1331300560 14084528 5051520
21:53:22 53265 8192000000 10267874000 10709979136 1354970368 14.192 100 37 74 0 1381963296 18038288 8954640
21:55:16 100854 8192000000 10248365728 10703712256 1326489472 13.935 97 14 27 1 1344326400 13917984 3918944
21:57:57 45615 8192000000 10235942368 10692972544 1317868848 13.857 98 3 6 0 1330588672 12128656 591168
21:59:45 50924 8192000000 10248376144 10705948672 1343532752 14.089 99 16 32 0 1353771200 6795056 3443392
22:01:38 91676 8192000000 10276021792 10734510080 1350188352 14.149 98 20 40 0 1366687200 11907232 4591616
22:04:25 63073 8192000000 10275730560 10727809024 1353817840 14.182 99 19 38 0 1369100560 11090928 4191792
22:06:28 41161 8192000000 10276180624 10722234368 1366027680 14.291 100 13 26 1 1381231648 12482512 2721456
22:08:11 87116 8192000000 10278414544 10730270720 1350497472 14.152 98 12 24 0 1361029536 8341040 2191024
22:10:41 67128 8192000000 10299175920 10764275712 1379374256 14.411 99 16 32 0 1395264960 11950032 3940672
22:12:48 108854 8192000000 10286427072 10744799232 1379278512 14.410 99 13 26 0 1391699344 8951280 3469552
22:15:58 63092 8192000000 10286236096 10762784768 1394464192 14.546 100 17 34 1 1407536688 9402576 3669920
22:18:02 94100 8192000000 10303371152 10762989568 1357224112 14.212 96 14 28 0 1373931888 13332480 3375296
22:20:38 77323 8192000000 10302218192 10776612864 1382085216 14.435 99 15 28 0 1409380096 23856080 3438800
22:24:07 83433 8192000000 10294181568 10745593856 1336112960 14.022 96 31 62 0 1359124080 15667152 7343968
22:26:32 71494 8192000000 10296458464 10771591168 1392116048 14.525 100 13 26 0 1409967568 15232208 2619312
22:28:48 93242 8192000000 10282191232 10730758144 1325193408 13.924 96 18 36 0 1339402240 10273632 3935200
22:31:27 60823 8192000000 10267812736 10703646720 1302487744 13.718 95 32 64 1 1319009712 9322480 7199488
22:33:29 23605 8192000000 10296231952 10728546304 1358885536 14.227 99 50 100 0 1382364240 12899408 10579296
22:34:58 100567 8192000000 10266935312 10733019136 1353516080 14.179 99 26 52 0 1371366560 11725072 6125408
22:37:39 63334 8192000000 9865974000 10273087488 988495488 10.767 72 3 6 0 1000052288 11267648 289152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-05-2022 22:41:02 to 29-05-2022 22:41:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 124 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-05-2022 22:41:17 to 29-05-2022 22:41: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          233374
MON$OLDEST_ACTIVE               233828
MON$OLDEST_SNAPSHOT             233828
MON$NEXT_TRANSACTION            233828
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                       2018304
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:136: 
:137: mscs-test-161.budzdorov.ru    Sun May 29 22:38:54 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:139: 
--- end of diff output ---

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

29.05.22 22:41: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-05-29 22:41:23 No severe PSQL-related problems occured
29.05.22 22:41:24. End of report.