Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2022 22:39
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 15451508 kB
MemAvailable 30388012 kB
Buffers 0 kB
Cached 16237928 kB
SwapCached 11600 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.11
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-12-30 22:38:40
DTS_END                         2022-12-30 22:38:40.2720
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/43362
TRN_ID                          239811
ATT_ID                          628
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: 9266.58 21091 1111989
customer order: creation 169.98 378 20398
customer order: refuse 14.33 340 1719
order to supplier: creation 45.98 1156 5517
order to supplier: removal 3.44 92481 413
invoice (draft): creation 43.63 3169 5235
invoice (draft): removal 53.59 707 6431
invoice accept: apply 40.57 18028 4868
invoice accept: cancel 48.73 825 5847
customer reserve: creation 3801.84 176 456221
customer reserve: removal 4755.40 52 570648
realization accept: apply 82.01 211 9841
realization accept: cancel 81.78 94 9813
payment from customer: creation 57.65 156 6918
payment from customer: removal 5.28 104 634
payment to supplier: creation 47.14 191 5657
payment to supplier: removal 3.50 108 420
service: total inventory turnovers 4.66 995 559
service: total monetary turnovers 6.21 28 745
service: aggregate perf. data 0.84 15584 101
service: refresh index statistics 0.03 287046 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2022 22:39:36 to 30-12-2022 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 752
TEST_TIME 2 1309
TEST_TIME 3 925
TEST_TIME 4 1610
TEST_TIME 5 2316
TEST_TIME 6 2915
TEST_TIME 7 3653
TEST_TIME 8 3918
TEST_TIME 9 4123
TEST_TIME 10 4375
TEST_TIME 11 4609
TEST_TIME 12 4650
TEST_TIME 13 5032
TEST_TIME 14 6029
TEST_TIME 15 6186
TEST_TIME 16 6269
TEST_TIME 17 6512
TEST_TIME 18 6593
TEST_TIME 19 7280
TEST_TIME 20 8551
TEST_TIME 21 8668
TEST_TIME 22 8634
TEST_TIME 23 9134
TEST_TIME 24 9387
TEST_TIME 25 9589
TEST_TIME 26 9894
TEST_TIME 27 10176
TEST_TIME 28 10379
TEST_TIME 29 10327
TEST_TIME 30 10129
TEST_TIME 31 10000
TEST_TIME 32 10032
TEST_TIME 33 9899
TEST_TIME 34 9951
TEST_TIME 35 9964
TEST_TIME 36 9874
TEST_TIME 37 9926
TEST_TIME 38 9769
TEST_TIME 39 9723
TEST_TIME 40 9742
TEST_TIME 41 9605
TEST_TIME 42 9503
TEST_TIME 43 9359
TEST_TIME 44 9474
TEST_TIME 45 9380
TEST_TIME 46 9344
TEST_TIME 47 9338
TEST_TIME 48 9239
TEST_TIME 49 9143
TEST_TIME 50 9140
TEST_TIME 51 9105
TEST_TIME 52 9094
TEST_TIME 53 9106
TEST_TIME 54 9156
TEST_TIME 55 9298
TEST_TIME 56 9374
TEST_TIME 57 9436
TEST_TIME 58 9346
TEST_TIME 59 9538
TEST_TIME 60 9563
TEST_TIME 61 9508
TEST_TIME 62 9540
TEST_TIME 63 9488
TEST_TIME 64 9375
TEST_TIME 65 9327
TEST_TIME 66 9294
TEST_TIME 67 9275
TEST_TIME 68 9268
TEST_TIME 69 9209
TEST_TIME 70 9208
TEST_TIME 71 9201
TEST_TIME 72 9193
TEST_TIME 73 9171
TEST_TIME 74 9193
TEST_TIME 75 9220
TEST_TIME 76 9246
TEST_TIME 77 9268
TEST_TIME 78 9379
TEST_TIME 79 9358
TEST_TIME 80 9447
TEST_TIME 81 9442
TEST_TIME 82 9513
TEST_TIME 83 9496
TEST_TIME 84 9557
TEST_TIME 85 9462
TEST_TIME 86 9420
TEST_TIME 87 9402
TEST_TIME 88 9367
TEST_TIME 89 9372
TEST_TIME 90 9339
TEST_TIME 91 9276
TEST_TIME 92 9234
TEST_TIME 93 9312
TEST_TIME 94 9335
TEST_TIME 95 9304
TEST_TIME 96 9400
TEST_TIME 97 9400
TEST_TIME 98 9369
TEST_TIME 99 9379
TEST_TIME 100 9457
TEST_TIME 101 9462
TEST_TIME 102 9493
TEST_TIME 103 9496
TEST_TIME 104 9509
TEST_TIME 105 9506
TEST_TIME 106 9518
TEST_TIME 107 9449
TEST_TIME 108 9387
TEST_TIME 109 9382
TEST_TIME 110 9369
TEST_TIME 111 9307
TEST_TIME 112 9270
TEST_TIME 113 9255
TEST_TIME 114 9300
TEST_TIME 115 9280
TEST_TIME 116 9310
TEST_TIME 117 9292
TEST_TIME 118 9301
TEST_TIME 119 9304
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2022 22:39:40 to 30-12-2022 22:39:43.

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 1534017 1534017 0 0.00 0 28304 6 0 0
doc_list_biud 1534015 1534015 0 0.00 0 40055 25 0 0
sp_add_invoice_to_stock 5060 4868 192 3.79 30 184638 18028 0 192
sp_cancel_adding_invoice 5847 5847 0 0.00 4 67442 826 0 0
sp_cancel_client_order 1719 1719 0 0.00 4 11120 341 0 0
sp_cancel_customer_reserve 570648 570648 0 0.00 0 27305 53 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 7372 104 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 16233 109 0 0
sp_cancel_supplier_invoice 6449 6431 18 0.27 2 26390 708 0 18
sp_cancel_supplier_order 413 413 0 0.00 6 1156310 92481 0 0
sp_cancel_write_off 9813 9813 0 0.00 1 9552 95 0 0
sp_client_order 20398 20398 0 0.00 3 24653 379 0 0
sp_customer_reserve 456221 456221 0 0.00 0 64979 177 0 0
sp_fill_shopping_cart 31134 31134 0 0.00 1 13618 36 0 0
sp_fill_shopping_cart_clo_res 456220 456220 0 0.00 0 21366 15 0 0
sp_get_clo_for_invoice 4867 4867 0 0.00 0 22003 358 0 0
sp_kill_cost_storno 16714 16714 0 0.00 0 11922 55 0 0
sp_kill_qstorno_ret_qs2qd 1157953 1157953 0 0.00 0 27301 28 0 0
sp_kill_qty_storno 609578 609578 0 0.00 0 40054 62 0 0
sp_lock_selected_doc 20261 20261 0 0.00 0 9411 6 0 0
sp_make_cost_storno 27283 27283 0 0.00 0 28295 72 0 0
sp_multiply_rows_for_pdistr 27282 27282 0 0.00 0 20953 34 0 0
sp_multiply_rows_for_qdistr 20398 20398 0 0.00 0 24581 188 0 0
sp_pay_from_customer 6918 6918 0 0.00 3 23169 156 0 0
sp_pay_to_supplier 5657 5657 0 0.00 4 23316 191 0 0
sp_payment_common 12575 12575 0 0.00 1 23142 65 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 11063 302 0 0
sp_qd_handle_on_invoice_upd_sts 10713 10713 0 0.00 0 40052 327 0 0
sp_qd_handle_on_reserve_upd_sts 9841 9841 0 0.00 0 21771 58 0 0
sp_reserve_write_off 9841 9841 0 0.00 6 28317 211 0 0
sp_supplier_invoice 5235 5235 0 0.00 4 79563 3169 0 0
sp_supplier_order 5517 5517 0 0.00 5 30091 1156 0 0
srv_aggregate_perf_data 102 101 1 0.98 2397 166645 15585 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1968 118710 46963 0 0
srv_find_qd_qs_mism 1064989 1064989 0 0.00 0 14490 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 27 32157 996 0 0
srv_make_money_saldo 745 745 0 0.00 1 2133 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 162842 397300 287047 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 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 4868 4868 0 0.00 0 9072 146 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 2845 135 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 3897 15 0 0
t$perf-norm:sp_cancel_customer_reserve 603 603 0 0.00 0 26530 56 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 1651 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 4643 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 935 16 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 27006 1524 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 1431 4 0 0
t$perf-norm:sp_client_order 20398 20398 0 0.00 0 7761 13 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 3600 15 0 0
t$perf-norm:sp_pay_from_customer 6918 6918 0 0.00 0 26529 14 0 0
t$perf-norm:sp_pay_to_supplier 5657 5657 0 0.00 0 6233 11 0 0
t$perf-norm:sp_reserve_write_off 9841 9841 0 0.00 0 3955 8 0 0
t$perf-norm:sp_supplier_invoice 5235 5235 0 0.00 0 6505 18 0 0
t$perf-norm:sp_supplier_order 5517 5517 0 0.00 0 8978 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 195 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1714 35 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1844 21 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 1699 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10116 9732 384 3.79 0 8317 24 0 384
v_all_customers 36638 36638 0 0.00 0 159 1 0 0
v_all_suppliers 29962 29962 0 0.00 0 24 1 0 0
v_all_wares 40796 40796 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1981 11 0 0
v_cancel_client_order 3438 3438 0 0.00 0 10375 10 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 4857 14 0 0
v_cancel_customer_reserve 1206 1206 0 0.00 0 2151 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 16196 24 0 0
v_cancel_supplier_invoice 850 814 36 4.23 0 1838 19 0 36
v_cancel_supplier_order 826 826 0 0.00 0 1250 6 0 0
v_cancel_write_off 1884 1884 0 0.00 0 3177 17 0 0
v_max_id_clo_ord 5517 5517 0 0.00 0 1185 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 1795 15 0 0
v_max_id_ord_sup 5219 5219 0 0.00 0 3691 4 0 0
v_max_non_paid_invoice 1412 1412 0 0.00 0 1698 4 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 5992 8 0 0
v_min_id_clo_ord 5517 5517 0 0.00 0 3658 6 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 9803 17 0 0
v_min_id_ord_sup 5219 5219 0 0.00 0 8095 14 0 0
v_min_non_paid_invoice 5657 5657 0 0.00 0 20038 51 0 0
v_min_non_paid_realizn 6918 6918 0 0.00 0 22308 57 0 0
v_our_firm 10482 10482 0 0.00 0 153 1 0 0
v_reserve_write_off 19682 19682 0 0.00 0 16197 11 0 0
x_lock_depdocs_on_canc_invoice 11871 11871 0 0.00 0 17019 115 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 8739 350 0 0
x_make_qstorno_1000_1200 5517 5517 0 0.00 1 30084 1082 0 0
x_make_qstorno_1200_2000 5235 5235 0 0.00 2 79552 3037 0 0
x_make_qstorno_2100_3300 456221 456221 0 0.00 0 64783 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2022 22:39:44 to 30-12-2022 22:39:48.

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:44 3 8192000000 8798184112 8809119744 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:44 42115 8192000000 9895580128 10206052352 980821536 10.692 100 90 181 0 1013850272 9671632 23357104
20:11:34 10909 8192000000 9944012576 10262495232 1026051872 11.130 100 91 182 0 1057218448 7981808 23184768
20:12:49 8678 8192000000 9968041456 10298548224 1056834064 11.426 100 80 160 0 1086618240 9512800 20271376
20:14:02 9461 8192000000 10033429840 10378387456 1119090784 12.018 100 85 168 1 1150103392 10894688 20117920
20:15:22 6415 8192000000 10096513744 10457456640 1182237328 12.611 100 77 154 1 1212389344 12838336 17313680
20:16:30 3927 8192000000 10125497360 10494279680 1217588400 12.939 100 82 163 0 1248788384 13470416 17729568
20:17:40 2970 8192000000 10155473488 10531930112 1249994464 13.238 100 79 153 2 1278715472 12216336 16504672
20:18:45 904 8192000000 10193810848 10572480512 1277716720 13.492 100 94 187 1 1313193296 14608000 20868576
20:19:47 1822 8192000000 10208513296 10594947072 1297182400 13.670 100 83 163 1 1326705568 11708752 17814416
20:20:51 7257 8192000000 10216783280 10610212864 1311456128 13.799 100 72 144 0 1337865008 9777872 16631008
20:21:59 3766 8192000000 10206880640 10615640064 1314443392 13.826 100 37 74 0 1329587968 6983952 8160624
20:23:05 7185 8192000000 10206733600 10623520768 1323423856 13.908 100 30 60 1 1337490208 6561504 7504848
20:24:13 13669 8192000000 10216414400 10629378048 1327709232 13.946 100 25 50 0 1340026432 7033712 5283488
20:25:30 22564 8192000000 10222622656 10641698816 1331416368 13.980 100 33 66 1 1344788736 6330352 7042016
20:26:53 7431 8192000000 10224249984 10649620480 1338924688 14.048 100 18 36 1 1353509120 10481536 4102896
20:28:03 10312 8192000000 10198782464 10617364480 1303619952 13.728 99 17 35 0 1312320128 5055984 3644192
20:29:17 7502 8192000000 10222813088 10621743104 1258193520 13.313 95 57 114 3 1286838576 15588896 13056160
20:31:02 5593 8192000000 10170460048 10571558912 1256837696 13.301 98 34 66 1 1271076672 6711776 7527200
20:32:09 6421 8192000000 10129193024 10524741632 1197673472 12.755 96 25 49 0 1209180608 5460848 6046288
20:33:18 13357 8192000000 10135872096 10541301760 1233471936 13.086 98 26 51 1 1246002016 5909200 6620880
20:34:31 51698 8192000000 10179742672 10574602240 1274940384 13.467 100 43 88 0 1290594352 6051744 9602224
20:36:24 5622 8192000000 10188514624 10591490048 1293625120 13.637 100 29 58 0 1306835984 6911536 6299328
20:37:32 6138 8192000000 10196625024 10604937216 1300491872 13.700 100 22 44 0 1311437552 5547328 5398352
20:38:41 112542 8192000000 10249257280 10665857024 1341855616 14.074 100 32 64 0 1357846528 7464672 8526240
20:41:55 45053 8192000000 10273120800 10691903488 1356829488 14.209 100 45 89 0 1392698704 25585408 10283808
20:44:31 22348 8192000000 10286741280 10712080384 1371218336 14.338 100 32 62 0 1406470304 28195664 7056304
20:45:57 5491 8192000000 10310128144 10728042496 1383409184 14.447 100 51 101 1 1420894896 25620576 11865136
20:47:10 7110 8192000000 10323985680 10735067136 1390857552 14.514 100 70 140 0 1437734496 30219840 16657104
20:48:26 10635 8192000000 10324498848 10745761792 1397857040 14.576 100 49 98 1 1440484656 30243776 12383840
20:49:47 6703 8192000000 10340126048 10756722688 1388389792 14.491 99 56 110 2 1438629600 35751920 14487888
20:50:57 3939 8192000000 10347168608 10769117184 1403347632 14.625 100 61 122 0 1462237088 43205104 15684352
20:52:08 3771 8192000000 10353594992 10772250624 1405589456 14.645 100 68 136 0 1464352832 41692944 17070432
20:53:17 1968 8192000000 10334962512 10738479104 1379460944 14.412 100 77 154 0 1439639696 40880640 19298112
20:54:23 5017 8192000000 10355858848 10771632128 1405906464 14.648 100 66 128 0 1464649664 41374992 17368208
20:55:36 9242 8192000000 10342553824 10757316608 1385312304 14.464 100 64 129 0 1451642176 49435360 16894512
20:56:55 3818 8192000000 10331039376 10746400768 1366838032 14.299 100 55 110 0 1441791264 59768768 15184464
20:58:05 2790 8192000000 10328632816 10732875776 1353716400 14.181 100 73 144 0 1431042448 59649264 17676784
20:59:24 8143 8192000000 10271687168 10702376960 1344036912 14.094 100 23 46 0 1386506976 36516288 5953776
21:00:38 16675 8192000000 10267875376 10695819264 1332848864 13.993 100 17 34 0 1374848848 36900080 5099904
21:02:00 21680 8192000000 10273238176 10708197376 1348500992 14.134 100 17 32 0 1384518240 31544688 4472560
21:03:56 41686 8192000000 10261878160 10697084928 1308064816 13.769 97 18 36 1 1346014208 33520736 4428656
21:05:43 38814 8192000000 10263857120 10706243584 1349850480 14.146 100 19 40 0 1374432480 20024448 4557552
21:08:32 57104 8192000000 10277019168 10715574272 1349647616 14.144 99 41 81 1 1372072256 13769104 8655536
21:10:50 33096 8192000000 10289033600 10718679040 1369689200 14.324 100 42 85 2 1392446160 12901296 9855664
21:12:24 25822 8192000000 10298820496 10731843584 1376105312 14.382 99 53 106 0 1397455808 9323216 12027280
21:13:57 52690 8192000000 10288473104 10738757632 1373568544 14.359 99 21 41 0 1393225296 14603920 5052832
21:15:55 49618 8192000000 10284421312 10737557504 1376650352 14.387 99 11 21 0 1389538544 10473376 2414816
21:17:45 35351 8192000000 10279950000 10733215744 1372100272 14.346 99 17 34 1 1386434848 10635168 3699408
21:19:22 51022 8192000000 10294823472 10753355776 1393946560 14.541 100 15 29 0 1410857664 14462832 2448272
21:21:19 65908 8192000000 10271289424 10736009216 1346384864 14.115 97 24 48 0 1372108464 19554224 6169376
21:23:26 31035 8192000000 10290294208 10729992192 1380683120 14.423 100 46 85 1 1399059136 7923632 10452384
21:24:58 45255 8192000000 10275321984 10739601408 1379848384 14.415 99 18 36 0 1393976944 9740352 4388208
21:26:44 64580 8192000000 10278469456 10751401984 1388673792 14.494 100 7 14 0 1403989360 13655200 1660368
21:28:49 49951 8192000000 10287940576 10742235136 1370838560 14.335 98 14 28 2 1383423152 8935552 3649040
21:30:42 74976 8192000000 10314255232 10773241856 1393302784 14.535 99 15 29 0 1413538224 16906672 3328768
21:32:59 71912 8192000000 10292550288 10753499136 1361077680 14.247 97 16 32 0 1388592720 23432832 4082208
21:35:28 83524 8192000000 10315273616 10752290816 1306663632 13.756 93 33 66 0 1326157888 12162192 7332064
21:37:52 78256 8192000000 10316601984 10753531904 1351955728 14.165 97 15 30 0 1372146832 17144240 3046864
21:40:13 68375 8192000000 10304552800 10752557056 1376770544 14.388 99 21 41 0 1393841776 12747184 4324048
21:42:29 82812 8192000000 10296252496 10753933312 1370810384 14.334 98 13 26 0 1382064400 8114688 3139328
21:44:53 83530 8192000000 10281683536 10751602688 1374065376 14.363 99 13 26 0 1394309888 17676160 2568352
21:47:24 86447 8192000000 10258968192 10721325056 1337585312 14.036 97 12 25 0 1347682736 7562992 2534432
21:49:53 74656 8192000000 10261218336 10718744576 1318206848 13.860 96 8 16 0 1326927280 7062656 1657776
21:52:08 40185 8192000000 10285155488 10738634752 1377171008 14.391 100 31 62 0 1397825664 13676640 6978016
21:53:55 81934 8192000000 10246588640 10688425984 1284775232 13.557 93 24 48 0 1301253312 10575088 5902992
21:56:17 33449 8192000000 10239430832 10695716864 1306144240 13.751 97 18 36 0 1322908112 12440016 4323856
21:57:52 44499 8192000000 10255481728 10708316160 1337412208 14.034 99 20 40 0 1358228400 16343168 4473024
21:59:36 54324 8192000000 10262931232 10709565440 1319132256 13.869 97 25 50 0 1339746352 14992816 5621280
22:01:33 45362 8192000000 10268201312 10704748544 1334484880 14.008 99 30 59 0 1354483296 13391568 6606848
22:03:23 50990 8192000000 10255505136 10703978496 1343255920 14.087 99 41 82 0 1360759248 8213104 9290224
22:05:21 32238 8192000000 10269659504 10715901952 1358337232 14.222 99 28 55 2 1373121808 8907712 5876864
22:07:14 61873 8192000000 10235928608 10696507392 1318241136 13.861 97 7 14 0 1329989120 9819232 1928752
22:09:24 90807 8192000000 10271303648 10726641664 1355869344 14.200 99 11 22 0 1369367408 10854176 2643888
22:12:01 9566 8192000000 10274864176 10721234944 1362089760 14.256 99 41 83 0 1378801040 6618064 10093216
22:13:20 46539 8192000000 10301327312 10751664128 1384655648 14.458 99 26 52 0 1399578816 9041856 5881312
22:15:08 40615 8192000000 10293699760 10746535936 1381735824 14.432 100 13 26 0 1398405104 14049936 2619344
22:16:52 48099 8192000000 10321158368 10765844480 1380961072 14.425 99 42 84 0 1409637712 19461584 9215056
22:18:40 40136 8192000000 10297668064 10744893440 1372368432 14.348 99 18 36 0 1388770784 12600944 3801408
22:20:24 48545 8192000000 10277444288 10718674944 1328327520 13.952 96 17 34 0 1346037392 14053728 3656144
22:22:17 67487 8192000000 10271829936 10702852096 1309312656 13.780 95 37 74 0 1333586896 16636896 7637344
22:24:26 118710 8192000000 10277260960 10732802048 1360294896 14.240 99 18 36 0 1383010352 18587264 4128192
22:27:29 52291 8192000000 10261924992 10727280640 1368927040 14.317 100 17 35 0 1380138768 7577568 3634160
22:29:28 76635 8192000000 10271842864 10726989824 1351735552 14.163 98 9 18 0 1365286928 11429056 2122320
22:31:47 88964 8192000000 10266085952 10722611200 1350088544 14.148 98 7 14 0 1360348464 8644608 1615312
22:34:21 76419 8192000000 10269459088 10727849984 1349494000 14.143 98 10 20 0 1359993536 8483312 2016224
22:36:39 75585 8192000000 10266308864 10719547392 1293724720 13.638 94 21 41 0 1307988352 9793584 4470048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-12-2022 22:39:50 to 30-12-2022 22:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 192 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2022 22:40:06 to 30-12-2022 22:40:08.

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          237506
MON$OLDEST_ACTIVE               239948
MON$OLDEST_SNAPSHOT             239948
MON$NEXT_TRANSACTION            239948
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                       2013888
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1454: 
:1455: mscs-test-161.budzdorov.ru    Fri Dec 30 22:38:45 2022
:1456:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1457: 
:1458: 
:1459: mscs-test-161.budzdorov.ru    Fri Dec 30 22:38:51 2022
:1460:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1461: 
--- end of diff output ---

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

30.12.22 22:40:09. 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-12-30 22:40:12 No severe PSQL-related problems occured
30.12.22 22:40:13. End of report.