Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2022 16: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 16740184 kB
MemAvailable 31567192 kB
Buffers 0 kB
Cached 14961532 kB
SwapCached 2220 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-04-03 16:38:09
DTS_END                         2022-04-03 16:38:09.3970
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/39236
TRN_ID                          241110
ATT_ID                          723
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: 9270.64 26923 1112477
customer order: creation 169.73 385 20368
customer order: refuse 14.75 373 1770
order to supplier: creation 45.47 1320 5456
order to supplier: removal 3.57 99752 428
invoice (draft): creation 45.78 3308 5493
invoice (draft): removal 52.05 863 6246
invoice accept: apply 41.23 18504 4948
invoice accept: cancel 47.00 806 5640
customer reserve: creation 3914.28 177 469713
customer reserve: removal 4641.07 60 556928
realization accept: apply 82.17 188 9860
realization accept: cancel 83.80 107 10056
payment from customer: creation 59.63 112 7155
payment from customer: removal 5.63 60 676
payment to supplier: creation 48.54 124 5825
payment to supplier: removal 3.43 72 412
service: total inventory turnovers 4.85 984 582
service: total monetary turnovers 6.93 30 831
service: aggregate perf. data 0.72 24725 86
service: refresh index statistics 0.03 386510 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2022 16:39:04 to 03-04-2022 16:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4926
TEST_TIME 2 2795
TEST_TIME 3 2848
TEST_TIME 4 3126
TEST_TIME 5 3878
TEST_TIME 6 4588
TEST_TIME 7 4517
TEST_TIME 8 4726
TEST_TIME 9 4957
TEST_TIME 10 5226
TEST_TIME 11 5596
TEST_TIME 12 6059
TEST_TIME 13 6177
TEST_TIME 14 6488
TEST_TIME 15 6622
TEST_TIME 16 7073
TEST_TIME 17 7412
TEST_TIME 18 7650
TEST_TIME 19 8033
TEST_TIME 20 8030
TEST_TIME 21 8447
TEST_TIME 22 8512
TEST_TIME 23 8715
TEST_TIME 24 9152
TEST_TIME 25 10570
TEST_TIME 26 10911
TEST_TIME 27 10839
TEST_TIME 28 10500
TEST_TIME 29 10235
TEST_TIME 30 9999
TEST_TIME 31 10021
TEST_TIME 32 10134
TEST_TIME 33 10068
TEST_TIME 34 9931
TEST_TIME 35 9890
TEST_TIME 36 9743
TEST_TIME 37 9623
TEST_TIME 38 9412
TEST_TIME 39 9575
TEST_TIME 40 9435
TEST_TIME 41 9368
TEST_TIME 42 9267
TEST_TIME 43 9188
TEST_TIME 44 9177
TEST_TIME 45 9092
TEST_TIME 46 8944
TEST_TIME 47 9057
TEST_TIME 48 9250
TEST_TIME 49 9212
TEST_TIME 50 9159
TEST_TIME 51 9143
TEST_TIME 52 9212
TEST_TIME 53 9307
TEST_TIME 54 9227
TEST_TIME 55 9306
TEST_TIME 56 9307
TEST_TIME 57 9252
TEST_TIME 58 9354
TEST_TIME 59 9475
TEST_TIME 60 9392
TEST_TIME 61 9273
TEST_TIME 62 9271
TEST_TIME 63 9281
TEST_TIME 64 9275
TEST_TIME 65 9283
TEST_TIME 66 9227
TEST_TIME 67 9216
TEST_TIME 68 9217
TEST_TIME 69 9238
TEST_TIME 70 9182
TEST_TIME 71 9193
TEST_TIME 72 9191
TEST_TIME 73 9322
TEST_TIME 74 9318
TEST_TIME 75 9416
TEST_TIME 76 9392
TEST_TIME 77 9452
TEST_TIME 78 9467
TEST_TIME 79 9422
TEST_TIME 80 9412
TEST_TIME 81 9373
TEST_TIME 82 9319
TEST_TIME 83 9347
TEST_TIME 84 9330
TEST_TIME 85 9285
TEST_TIME 86 9233
TEST_TIME 87 9221
TEST_TIME 88 9238
TEST_TIME 89 9252
TEST_TIME 90 9213
TEST_TIME 91 9272
TEST_TIME 92 9262
TEST_TIME 93 9340
TEST_TIME 94 9330
TEST_TIME 95 9312
TEST_TIME 96 9420
TEST_TIME 97 9444
TEST_TIME 98 9446
TEST_TIME 99 9463
TEST_TIME 100 9499
TEST_TIME 101 9503
TEST_TIME 102 9538
TEST_TIME 103 9508
TEST_TIME 104 9445
TEST_TIME 105 9476
TEST_TIME 106 9425
TEST_TIME 107 9417
TEST_TIME 108 9394
TEST_TIME 109 9357
TEST_TIME 110 9334
TEST_TIME 111 9328
TEST_TIME 112 9284
TEST_TIME 113 9309
TEST_TIME 114 9277
TEST_TIME 115 9284
TEST_TIME 116 9268
TEST_TIME 117 9320
TEST_TIME 118 9296
TEST_TIME 119 9346
TEST_TIME 120 9277

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-04-2022 16:39:07 to 03-04-2022 16:39:11.

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 1547087 1547087 0 0.00 0 27983 6 0 0
doc_list_biud 1547087 1547087 0 0.00 0 34727 28 0 0
sp_add_invoice_to_stock 5041 4948 93 1.84 26 203875 18505 0 93
sp_cancel_adding_invoice 5640 5640 0 0.00 4 54147 806 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 13461 373 0 0
sp_cancel_customer_reserve 556928 556928 0 0.00 1 34733 60 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 3651 60 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 7470 73 0 0
sp_cancel_supplier_invoice 6252 6246 6 0.09 2 18282 863 0 6
sp_cancel_supplier_order 428 428 0 0.00 6 1216396 99753 0 0
sp_cancel_write_off 10056 10056 0 0.00 1 9733 108 0 0
sp_client_order 20368 20368 0 0.00 3 29964 385 0 0
sp_customer_reserve 469713 469713 0 0.00 0 41116 178 0 0
sp_fill_shopping_cart 31315 31315 0 0.00 1 13321 41 0 0
sp_fill_shopping_cart_clo_res 469703 469703 0 0.00 0 30917 16 0 0
sp_get_clo_for_invoice 4938 4938 0 0.00 0 20789 301 0 0
sp_kill_cost_storno 16784 16784 0 0.00 0 15378 56 0 0
sp_kill_qstorno_ret_qs2qd 1130586 1130586 0 0.00 0 34718 33 0 0
sp_kill_qty_storno 595866 595866 0 0.00 0 34726 71 0 0
sp_lock_selected_doc 20530 20530 0 0.00 0 9142 7 0 0
sp_make_cost_storno 27778 27778 0 0.00 0 13078 49 0 0
sp_multiply_rows_for_pdistr 27778 27778 0 0.00 0 7618 27 0 0
sp_multiply_rows_for_qdistr 20368 20368 0 0.00 0 22209 174 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 16329 112 0 0
sp_pay_to_supplier 5825 5825 0 0.00 4 20768 124 0 0
sp_payment_common 12980 12980 0 0.00 1 16282 28 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 13425 341 0 0
sp_qd_handle_on_invoice_upd_sts 10578 10578 0 0.00 0 32634 346 0 0
sp_qd_handle_on_reserve_upd_sts 9860 9860 0 0.00 0 17307 62 0 0
sp_reserve_write_off 9860 9860 0 0.00 6 18272 188 0 0
sp_supplier_invoice 5493 5493 0 0.00 7 105588 3309 0 0
sp_supplier_order 5456 5456 0 0.00 5 48871 1321 0 0
srv_aggregate_perf_data 87 86 1 1.14 2969 202893 24726 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1533 145596 49175 0 0
srv_find_qd_qs_mism 1064444 1064444 0 0.00 0 27982 6 0 0
srv_make_invnt_saldo 582 582 0 0.00 30 51305 985 0 0
srv_make_money_saldo 831 831 0 0.00 1 5473 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 287442 472037 386510 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4950 4950 0 0.00 0 9865 145 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 4426 116 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1361 14 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 9614 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 627 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2312 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 622 10 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 27416 1549 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 930 5 0 0
t$perf-norm:sp_client_order 20368 20368 0 0.00 0 6739 18 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 2925 9 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 13663 10 0 0
t$perf-norm:sp_pay_to_supplier 5825 5825 0 0.00 0 9608 12 0 0
t$perf-norm:sp_reserve_write_off 9860 9860 0 0.00 0 8883 10 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 9155 20 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 4316 15 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 363 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 478 9 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 781 21 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 804 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10062 9876 186 1.84 0 7605 20 0 186
v_all_customers 37594 37594 0 0.00 0 15 1 0 0
v_all_suppliers 30334 30334 0 0.00 0 42 1 0 0
v_all_wares 40736 40736 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 4320 23 0 0
v_cancel_client_order 3540 3540 0 0.00 0 3879 8 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3644 7 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 4052 11 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1059 5 0 0
v_cancel_supplier_invoice 828 816 12 1.44 0 6743 29 0 12
v_cancel_supplier_order 856 856 0 0.00 0 1968 8 0 0
v_cancel_write_off 1882 1882 0 0.00 0 4075 16 0 0
v_max_id_clo_ord 5456 5456 0 0.00 0 1378 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 2871 13 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 2655 3 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 841 3 0 0
v_max_non_paid_realizn 3670 3670 0 0.00 0 16277 8 0 0
v_min_id_clo_ord 5456 5456 0 0.00 0 8918 7 0 0
v_min_id_clo_res 987 987 0 0.00 0 771 3 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 7129 12 0 0
v_min_non_paid_invoice 5825 5825 0 0.00 0 4580 16 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 8264 24 0 0
v_our_firm 10112 10112 0 0.00 0 12 1 0 0
v_reserve_write_off 19720 19720 0 0.00 0 4654 8 0 0
x_lock_depdocs_on_canc_invoice 11478 11478 0 0.00 0 32648 124 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 9756 360 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 1 48647 1239 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 2 105549 3158 0 0
x_make_qstorno_2100_3300 469713 469713 0 0.00 0 41115 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 16:39:12 to 03-04-2022 16:39:16.

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
14:08:13 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:13 33810 8192000000 9887790112 10193772544 964443936 10.532 100 86 172 2 993794272 5607888 23742448
14:10:51 19879 8192000000 9942100544 10260598784 1018444208 11.057 100 88 176 1 1048971648 7578864 22948576
14:12:16 15095 8192000000 9982892112 10317213696 1060596096 11.462 100 78 156 0 1089323440 9147600 19579744
14:13:42 9916 8192000000 10052282624 10405388288 1140598832 12.221 100 78 156 1 1169796848 11391856 17806160
14:14:53 7056 8192000000 10109719536 10474917888 1198508848 12.762 100 80 160 2 1228858032 12456784 17892400
14:16:03 4429 8192000000 10147685744 10521092096 1232943264 13.081 100 78 156 0 1263527040 12869360 17714416
14:17:10 2712 8192000000 10180090832 10558734336 1264127952 13.368 100 83 167 1 1296082368 13771168 18183248
14:18:15 1744 8192000000 10210875968 10590793728 1291991376 13.622 100 90 181 2 1325952400 13617360 20343664
14:19:19 47297 8192000000 10209145408 10602696704 1301313328 13.707 100 59 118 1 1322215664 5454560 15447776
14:21:07 5310 8192000000 10204092304 10614886400 1310616816 13.792 100 36 71 2 1326867968 8125776 8125376
14:22:15 8739 8192000000 10198567296 10619539456 1319080992 13.868 100 26 51 1 1330775744 5424480 6270272
14:23:28 12485 8192000000 10210954240 10629419008 1324635168 13.919 100 21 40 0 1334762192 5826624 4300400
14:24:42 29657 8192000000 10250066864 10646286336 1329626912 13.964 100 68 138 2 1351445536 6599248 15219376
14:26:12 5611 8192000000 10235532240 10651303936 1337524320 14.035 100 30 60 0 1351410720 6852656 7033744
14:27:21 4761 8192000000 10231471776 10649980928 1335076080 14.013 100 28 54 4 1347344240 5875120 6393040
14:28:28 20058 8192000000 10162762560 10552336384 1139179760 12.208 86 18 36 0 1148156448 4353888 4622800
14:29:48 13369 8192000000 10171194880 10588872704 1289277984 13.598 100 32 63 1 1302543184 6073248 7191952
14:31:02 4711 8192000000 10149227664 10551177216 1248596368 13.225 99 34 68 0 1262106016 4947600 8562048
14:32:09 8317 8192000000 10145223296 10545614848 1242917600 13.173 99 38 76 0 1258667920 6171072 9579248
14:33:18 16387 8192000000 10166218816 10563665920 1245577936 13.198 98 29 58 1 1258432384 5265648 7588800
14:34:36 25214 8192000000 10170782512 10577055744 1277317888 13.489 100 16 32 0 1286656016 5672736 3665392
14:36:03 6738 8192000000 10191226256 10603528192 1297711360 13.674 100 23 45 1 1308976064 6069040 5195664
14:37:11 10462 8192000000 10196057216 10612908032 1306776400 13.757 100 16 32 1 1315767248 5169664 3821184
14:38:32 1818 8192000000 10236166112 10641780736 1329789424 13.965 100 48 97 2 1346012704 5788688 10434592
14:39:38 110707 8192000000 10261573600 10679951360 1349405840 14.142 100 34 68 2 1369960704 13117808 7437056
14:42:39 35949 8192000000 10283297008 10710532096 1374347552 14.366 100 38 76 0 1403275952 19466096 9462304
14:44:25 29129 8192000000 10305670736 10727882752 1386932160 14.478 100 41 82 0 1419552704 21972048 10648496
14:45:59 8208 8192000000 10314258032 10732490752 1391919728 14.523 100 53 106 1 1427345648 22401904 13024016
14:47:12 2148 8192000000 10344129456 10764705792 1410639904 14.690 100 62 122 3 1454686192 27893616 16152672
14:48:20 5055 8192000000 10344983808 10772262912 1409769648 14.682 100 49 98 0 1454778928 32892000 12117280
14:49:33 6702 8192000000 10338456736 10762727424 1388172880 14.490 99 59 118 2 1441227744 37998672 15056192
14:50:44 2246 8192000000 10349653424 10762690560 1396141728 14.561 100 71 142 0 1456371696 42665312 17564656
14:51:48 3549 8192000000 10360203200 10771173376 1400430288 14.599 100 78 148 3 1462726704 43339920 18956496
14:53:00 5452 8192000000 10359140720 10774315008 1401521520 14.609 100 66 132 0 1464114752 46026000 16567232
14:54:10 3299 8192000000 10366164560 10778517504 1398894256 14.585 100 80 160 3 1470550624 51900880 19755488
14:55:20 1530 8192000000 10367734496 10772918272 1387566384 14.484 99 90 180 1 1460551040 50080624 22904032
14:56:23 10543 8192000000 10323904864 10749337600 1360197552 14.239 98 52 105 0 1418972064 44219856 14554656
14:57:38 9804 8192000000 10340720224 10761646080 1393408288 14.536 100 38 75 0 1443017984 39167056 10442640
14:58:54 4527 8192000000 10358492816 10769801216 1387421088 14.483 99 64 128 1 1452055424 47809296 16825040
15:00:05 23726 8192000000 10339762976 10777698304 1397890000 14.576 100 34 68 1 1456435248 49021488 9523760
15:01:33 13260 8192000000 10329343008 10759221248 1367498192 14.305 100 34 68 0 1432418016 54744464 10175360
15:02:54 18548 8192000000 10326021072 10764447744 1359921024 14.237 99 20 40 1 1423052752 56874416 6257312
15:04:17 28295 8192000000 10277746208 10740125696 1371808912 14.343 100 14 28 0 1396120704 20810608 3501184
15:05:49 44497 8192000000 10305897488 10744324096 1384231904 14.454 100 39 78 2 1407190640 13375200 9583536
15:07:52 51699 8192000000 10304442576 10739224576 1374912576 14.371 99 38 74 3 1401221536 17643376 8665584
15:09:52 29103 8192000000 10294768640 10756677632 1383720816 14.450 100 9 19 0 1405388720 19332592 2335312
15:11:22 93117 8192000000 10267715600 10714890240 1336699120 14.028 97 15 30 0 1349843792 9632208 3512464
15:13:55 40544 8192000000 10260542128 10708914176 1363550640 14.269 100 5 10 0 1372112656 7866768 695248
15:15:38 145596 8192000000 10277161056 10751602688 1366954192 14.300 99 23 46 1 1383435760 11091152 5390416
15:19:23 19019 8192000000 10297789936 10751389696 1392770352 14.531 100 23 46 0 1405512464 7276240 5465872
15:20:46 33826 8192000000 10282808608 10739412992 1359723664 14.235 97 27 54 0 1377282128 10974560 6583904
15:22:58 73284 8192000000 10305210272 10763870208 1397733568 14.575 100 14 28 0 1413570592 12715504 3121520
15:25:17 74871 8192000000 10314344880 10786779136 1404686480 14.637 100 9 18 0 1424063840 17055984 2321376
15:28:00 32776 8192000000 10341791216 10785452032 1413548832 14.715 100 44 88 1 1432206032 8737344 9919856
15:29:33 123763 8192000000 10315360960 10781143040 1401323632 14.607 99 12 24 0 1419574512 15541184 2709696
15:32:38 24645 8192000000 10315233600 10764804096 1370153504 14.328 97 34 68 0 1391163984 13670880 7339600
15:34:05 111016 8192000000 10317735904 10769268736 1375898752 14.380 98 22 42 2 1401460880 20632608 4929520
15:36:56 62686 8192000000 10289397120 10735824896 1325255664 13.924 95 19 38 0 1338287760 8694640 4337456
15:39:04 115881 8192000000 10241282816 10682449920 1269486512 13.417 93 40 77 2 1290262864 11043008 9733344
15:42:23 49351 8192000000 10259301408 10717270016 1358876400 14.227 100 20 40 2 1374197168 10593520 4727248
15:45:14 63180 8192000000 10257120480 10716999680 1338331328 14.042 98 14 27 0 1350948464 9334576 3282560
15:47:18 47320 8192000000 10278052096 10731347968 1372817840 14.352 100 17 34 0 1388386912 11960832 3608240
15:49:05 60214 8192000000 10289576368 10753458176 1371983824 14.345 99 27 54 0 1395245376 16457760 6803792
15:51:06 66556 8192000000 10288459024 10736812032 1342833392 14.083 97 12 24 0 1354764512 9468896 2462224
15:53:14 59713 8192000000 10270173600 10736467968 1331790000 13.983 97 7 14 0 1352651456 19500752 1360704
15:55:46 108581 8192000000 10264474992 10705412096 1286286016 13.570 94 11 22 0 1300181584 11627088 2268480
15:58:41 83682 8192000000 10270793520 10727866368 1354438912 14.187 99 15 30 0 1366794176 9188720 3166544
16:01:13 80286 8192000000 10280238032 10737098752 1337726032 14.037 97 24 49 1 1356171120 12820928 5624160
16:03:37 55952 8192000000 10291486992 10750324736 1386339920 14.473 100 8 16 0 1399410256 11142096 1928240
16:05:33 73480 8192000000 10268662128 10737250304 1343636800 14.090 97 12 24 1 1365287392 18704752 2945840
16:07:51 77416 8192000000 10269140080 10719424512 1313805888 13.821 96 11 22 0 1329288560 13291536 2191136
16:10:09 46011 8192000000 10261069040 10710376448 1332629152 13.991 97 16 32 0 1346319312 9940656 3749504
16:11:56 57724 8192000000 10286827760 10739032064 1368534224 14.314 99 22 44 2 1381418352 8123200 4760928
16:13:57 69425 8192000000 10299671024 10763505664 1377227184 14.392 99 14 28 0 1397486720 16992672 3266864
16:16:08 63422 8192000000 10317514192 10774536192 1382447520 14.438 99 27 54 0 1406358304 17616464 6294320
16:18:15 60236 8192000000 10307180912 10754064384 1371595328 14.341 98 23 45 0 1387015872 10823312 4597232
16:20:26 69473 8192000000 10282602976 10730815488 1298112688 13.678 93 16 32 0 1311871680 10549008 3209984
16:22:42 101780 8192000000 10269929440 10736594944 1352635824 14.171 98 28 56 1 1373718784 14945792 6137168
16:25:33 40880 8192000000 10278097152 10731180032 1352188048 14.167 98 25 50 0 1365106192 7230912 5687232
16:27:19 80063 8192000000 10251922352 10708971520 1323808304 13.911 96 6 12 0 1334333104 8979568 1545232
16:29:48 59814 8192000000 10250097424 10703663104 1339071520 14.049 99 4 7 0 1347749312 8019808 657984
16:32:05 42589 8192000000 10250547856 10696585216 1307869376 13.767 96 12 24 0 1317488640 7414512 2204752
16:33:55 55754 8192000000 10270729952 10721460224 1334650304 14.009 98 22 44 2 1356333152 17028512 4654336
16:35:59 49104 8192000000 10252142880 10695553024 1285638080 13.564 95 24 48 0 1302310960 11155904 5516976
16:37:51 25946 8192000000 9152081136 9400307712 302505648 3.561 23 3 6 0 304935488 1966224 463616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-04-2022 16:39:18 to 03-04-2022 16:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-04-2022 16:39:33 to 03-04-2022 16:39:35.

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          242284
MON$OLDEST_ACTIVE               242285
MON$OLDEST_SNAPSHOT             242285
MON$NEXT_TRANSACTION            242285
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                       2022848
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
--- end of diff output ---

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

03.04.22 16:39:36. 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-04-03 16:39:38 No severe PSQL-related problems occured
03.04.22 16:39:40. End of report.