Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.05.2022 01: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 16557544 kB
MemAvailable 31448924 kB
Buffers 0 kB
Cached 15156588 kB
SwapCached 2640 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-16 01:38:14
DTS_END                         2022-05-16 01:38:14.1850
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/49504
TRN_ID                          240444
ATT_ID                          718
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: 9461.41 19700 1135369
customer order: creation 173.40 340 20808
customer order: refuse 14.67 366 1760
order to supplier: creation 46.25 1287 5550
order to supplier: removal 3.67 92593 440
invoice (draft): creation 46.09 3347 5531
invoice (draft): removal 53.43 756 6411
invoice accept: apply 42.45 17963 5094
invoice accept: cancel 48.50 799 5820
customer reserve: creation 3965.33 176 475839
customer reserve: removal 4773.04 56 572765
realization accept: apply 84.97 159 10196
realization accept: cancel 80.43 108 9652
payment from customer: creation 59.75 100 7170
payment from customer: removal 5.41 69 649
payment to supplier: creation 48.20 117 5784
payment to supplier: removal 3.26 84 391
service: total inventory turnovers 4.91 1079 589
service: total monetary turnovers 6.87 36 824
service: aggregate perf. data 0.77 24550 92
service: refresh index statistics 0.03 250020 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-05-2022 01:39:52 to 16-05-2022 01:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5312
TEST_TIME 2 5201
TEST_TIME 3 4909
TEST_TIME 4 4894
TEST_TIME 5 5151
TEST_TIME 6 5256
TEST_TIME 7 5414
TEST_TIME 8 5660
TEST_TIME 9 6019
TEST_TIME 10 6347
TEST_TIME 11 6484
TEST_TIME 12 7222
TEST_TIME 13 7535
TEST_TIME 14 7693
TEST_TIME 15 7959
TEST_TIME 16 7815
TEST_TIME 17 8110
TEST_TIME 18 8269
TEST_TIME 19 8652
TEST_TIME 20 8857
TEST_TIME 21 8807
TEST_TIME 22 9085
TEST_TIME 23 9428
TEST_TIME 24 9934
TEST_TIME 25 10603
TEST_TIME 26 11154
TEST_TIME 27 11189
TEST_TIME 28 11057
TEST_TIME 29 10782
TEST_TIME 30 10777
TEST_TIME 31 10645
TEST_TIME 32 10571
TEST_TIME 33 10541
TEST_TIME 34 10431
TEST_TIME 35 10345
TEST_TIME 36 10204
TEST_TIME 37 10100
TEST_TIME 38 9960
TEST_TIME 39 9918
TEST_TIME 40 10139
TEST_TIME 41 10141
TEST_TIME 42 10179
TEST_TIME 43 10077
TEST_TIME 44 10160
TEST_TIME 45 10049
TEST_TIME 46 9955
TEST_TIME 47 9943
TEST_TIME 48 9787
TEST_TIME 49 9792
TEST_TIME 50 9729
TEST_TIME 51 9627
TEST_TIME 52 9600
TEST_TIME 53 9691
TEST_TIME 54 9614
TEST_TIME 55 9770
TEST_TIME 56 9733
TEST_TIME 57 9744
TEST_TIME 58 9717
TEST_TIME 59 9798
TEST_TIME 60 9902
TEST_TIME 61 9889
TEST_TIME 62 9884
TEST_TIME 63 9910
TEST_TIME 64 9943
TEST_TIME 65 10025
TEST_TIME 66 10079
TEST_TIME 67 10042
TEST_TIME 68 9915
TEST_TIME 69 9899
TEST_TIME 70 9880
TEST_TIME 71 9801
TEST_TIME 72 9755
TEST_TIME 73 9773
TEST_TIME 74 9709
TEST_TIME 75 9736
TEST_TIME 76 9657
TEST_TIME 77 9663
TEST_TIME 78 9638
TEST_TIME 79 9661
TEST_TIME 80 9621
TEST_TIME 81 9578
TEST_TIME 82 9645
TEST_TIME 83 9714
TEST_TIME 84 9687
TEST_TIME 85 9677
TEST_TIME 86 9652
TEST_TIME 87 9632
TEST_TIME 88 9774
TEST_TIME 89 9713
TEST_TIME 90 9771
TEST_TIME 91 9765
TEST_TIME 92 9778
TEST_TIME 93 9695
TEST_TIME 94 9611
TEST_TIME 95 9645
TEST_TIME 96 9669
TEST_TIME 97 9610
TEST_TIME 98 9583
TEST_TIME 99 9550
TEST_TIME 100 9502
TEST_TIME 101 9513
TEST_TIME 102 9470
TEST_TIME 103 9439
TEST_TIME 104 9379
TEST_TIME 105 9403
TEST_TIME 106 9394
TEST_TIME 107 9377
TEST_TIME 108 9391
TEST_TIME 109 9409
TEST_TIME 110 9413
TEST_TIME 111 9426
TEST_TIME 112 9439
TEST_TIME 113 9437
TEST_TIME 114 9425
TEST_TIME 115 9431
TEST_TIME 116 9488
TEST_TIME 117 9466
TEST_TIME 118 9546
TEST_TIME 119 9544
TEST_TIME 120 9464

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-05-2022 01:39:56 to 16-05-2022 01:39:59.

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 1575512 1575512 0 0.00 0 15786 6 0 0
doc_list_biud 1575512 1575512 0 0.00 0 64609 26 0 0
sp_add_invoice_to_stock 5217 5094 123 2.35 46 176341 17964 0 123
sp_cancel_adding_invoice 5820 5820 0 0.00 4 70772 799 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 7759 367 0 0
sp_cancel_customer_reserve 572765 572765 0 0.00 1 23493 56 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 4100 69 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 2 6688 84 0 0
sp_cancel_supplier_invoice 6420 6411 9 0.14 2 64612 756 0 9
sp_cancel_supplier_order 440 440 0 0.00 7 931034 92593 0 0
sp_cancel_write_off 9652 9652 0 0.00 1 8310 109 0 0
sp_client_order 20808 20808 0 0.00 4 18471 340 0 0
sp_customer_reserve 475839 475839 0 0.00 0 129682 177 0 0
sp_fill_shopping_cart 31888 31888 0 0.00 1 9494 34 0 0
sp_fill_shopping_cart_clo_res 475835 475835 0 0.00 0 11164 13 0 0
sp_get_clo_for_invoice 5090 5090 0 0.00 0 22734 278 0 0
sp_kill_cost_storno 16512 16512 0 0.00 0 7836 55 0 0
sp_kill_qstorno_ret_qs2qd 1162033 1162033 0 0.00 0 64608 30 0 0
sp_kill_qty_storno 612134 612134 0 0.00 0 64609 66 0 0
sp_lock_selected_doc 21032 21032 0 0.00 0 7470 5 0 0
sp_make_cost_storno 28240 28240 0 0.00 0 12042 47 0 0
sp_multiply_rows_for_pdistr 28240 28240 0 0.00 0 8663 28 0 0
sp_multiply_rows_for_qdistr 20808 20808 0 0.00 0 18409 166 0 0
sp_pay_from_customer 7170 7170 0 0.00 4 12420 100 0 0
sp_pay_to_supplier 5784 5784 0 0.00 4 12352 118 0 0
sp_payment_common 12954 12954 0 0.00 1 11107 24 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 7749 340 0 0
sp_qd_handle_on_invoice_upd_sts 10910 10910 0 0.00 0 16316 326 0 0
sp_qd_handle_on_reserve_upd_sts 10196 10196 0 0.00 0 10822 51 0 0
sp_reserve_write_off 10196 10196 0 0.00 6 10846 159 0 0
sp_supplier_invoice 5531 5531 0 0.00 8 104247 3347 0 0
sp_supplier_order 5550 5550 0 0.00 5 67789 1287 0 0
srv_aggregate_perf_data 93 92 1 1.07 2727 327713 24551 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1483 123174 48543 0 0
srv_find_qd_qs_mism 1086827 1086827 0 0.00 0 15785 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 33 105953 1079 0 0
srv_make_money_saldo 824 824 0 0.00 0 5296 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 166021 342164 250020 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5094 5094 0 0.00 0 28065 211 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 5258 102 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 3211 19 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 3342 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 2277 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 1816 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2648 18 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 24067 1630 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 5132 15 0 0
t$perf-norm:sp_client_order 20808 20808 0 0.00 0 11710 24 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 3418 12 0 0
t$perf-norm:sp_pay_from_customer 7170 7170 0 0.00 0 11714 18 0 0
t$perf-norm:sp_pay_to_supplier 5784 5784 0 0.00 0 13058 17 0 0
t$perf-norm:sp_reserve_write_off 10196 10196 0 0.00 0 13549 15 0 0
t$perf-norm:sp_supplier_invoice 5531 5531 0 0.00 0 6502 24 0 0
t$perf-norm:sp_supplier_order 5550 5550 0 0.00 0 25279 23 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 201 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 151 3 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 1000 19 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 2647 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10420 10174 246 2.36 0 4227 16 0 246
v_all_customers 37772 37772 0 0.00 0 16 1 0 0
v_all_suppliers 30416 30416 0 0.00 0 17 1 0 0
v_all_wares 41616 41616 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 3622 10 0 0
v_cancel_client_order 3520 3520 0 0.00 0 1992 2 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2617 6 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 335 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 6679 14 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 510 11 0 18
v_cancel_supplier_order 880 880 0 0.00 0 462 4 0 0
v_cancel_write_off 2032 2032 0 0.00 0 661 11 0 0
v_max_id_clo_ord 5550 5550 0 0.00 0 358 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 2907 21 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 6792 6 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 639 3 0 0
v_max_non_paid_realizn 3875 3875 0 0.00 0 1964 4 0 0
v_min_id_clo_ord 5550 5550 0 0.00 0 3682 7 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 1500 5 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 5332 8 0 0
v_min_non_paid_invoice 5784 5784 0 0.00 0 6101 15 0 0
v_min_non_paid_realizn 7170 7170 0 0.00 0 11103 19 0 0
v_our_firm 10434 10434 0 0.00 0 18 1 0 0
v_reserve_write_off 20392 20392 0 0.00 0 3190 4 0 0
x_lock_depdocs_on_canc_invoice 11821 11821 0 0.00 0 31925 117 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 9724 339 0 0
x_make_qstorno_1000_1200 5550 5550 0 0.00 2 67772 1205 0 0
x_make_qstorno_1200_2000 5531 5531 0 0.00 4 99817 3234 0 0
x_make_qstorno_2100_3300 475839 475839 0 0.00 0 129677 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-05-2022 01:40:00 to 16-05-2022 01:40:04.

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
23:08:18 2 8192000000 8797703648 8808529920 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:19 28058 8192000000 9929153712 10244411392 1010397680 10.979 100 90 180 0 1040090880 6486192 23207008
23:10:50 24944 8192000000 9961218160 10287038464 1044312656 11.306 100 89 179 0 1075897168 9003264 22581248
23:12:25 15040 8192000000 10012623776 10346393600 1087973616 11.723 100 86 170 1 1119587184 10236736 21376832
23:13:43 13937 8192000000 10049857712 10396450816 1135334096 12.172 100 77 154 0 1164510160 10833360 18342704
23:15:00 9655 8192000000 10099538064 10458636288 1180357840 12.594 100 82 164 0 1211113920 11997936 18758144
23:16:13 5222 8192000000 10140638560 10514640896 1235946032 13.109 100 86 169 0 1265887568 11436560 18504976
23:17:21 2927 8192000000 10181524448 10559184896 1266397664 13.389 100 82 164 1 1298126272 13601760 18126848
23:18:25 1594 8192000000 10203290976 10585178112 1296077616 13.660 100 94 188 1 1331443456 13985456 21380384
23:19:29 1454 8192000000 10226268560 10611122176 1305673680 13.747 100 94 188 2 1342214800 15069376 21471744
23:20:31 3438 8192000000 10212105824 10614591488 1307879744 13.767 100 69 138 1 1334524736 10810992 15834000
23:21:37 4843 8192000000 10209489888 10621181952 1317954032 13.858 100 34 68 1 1332945152 7286112 7705008
23:22:42 14566 8192000000 10207628160 10624978944 1326507008 13.936 100 23 46 0 1336884464 4813056 5564400
23:23:57 5910 8192000000 10242528192 10645536768 1330122384 13.968 100 64 130 2 1350956000 6062752 14770864
23:25:03 5593 8192000000 10228238832 10645577728 1332756432 13.992 100 37 74 3 1348604416 7321296 8526688
23:26:11 8568 8192000000 10222999440 10644127744 1331919616 13.984 100 27 56 0 1344646000 6607520 6118864
23:27:21 8389 8192000000 10191412048 10604503040 1282010112 13.531 97 34 68 1 1295141328 5436448 7694768
23:28:33 12486 8192000000 10184448320 10601295872 1273584352 13.454 97 23 46 0 1284369184 5215600 5569232
23:29:47 4749 8192000000 10161206864 10574684160 1269872512 13.420 99 32 65 0 1284395328 6928032 7594784
23:30:53 11131 8192000000 10156309344 10557366272 1239581808 13.142 98 24 48 1 1251120064 5650768 5887488
23:32:04 15685 8192000000 10174687808 10567778304 1249713344 13.236 98 36 72 0 1264371280 5202720 9455216
23:33:56 8932 8192000000 10179190384 10580058112 1268014096 13.403 99 32 64 1 1281533152 6412336 7106720
23:35:05 8038 8192000000 10183831152 10596937728 1294833952 13.648 100 21 42 0 1305238928 5605280 4799696
23:36:14 9489 8192000000 10196730656 10608971776 1302839072 13.721 100 15 29 2 1311712192 5697168 3175952
23:37:24 42761 8192000000 10196472928 10614292480 1306729328 13.756 100 22 43 0 1315977728 4464736 4783664
23:39:16 22428 8192000000 10248812512 10660974592 1343414656 14.088 100 40 76 2 1359219872 7627680 8177536
23:40:44 14245 8192000000 10271325328 10689232896 1363183328 14.266 100 53 106 0 1385573504 9907632 12482544
23:42:03 6857 8192000000 10276035392 10700804096 1376133264 14.382 100 46 92 3 1399257312 11723840 11400208
23:43:15 20638 8192000000 10301012496 10717708288 1382476128 14.439 100 55 109 0 1411826960 16310624 13040208
23:44:40 8704 8192000000 10302413712 10720104448 1385505200 14.466 100 57 115 0 1414922336 15866352 13550784
23:45:52 1564 8192000000 10316246176 10728005632 1390306928 14.509 100 65 126 2 1424335488 19352592 14675968
23:47:05 2726 8192000000 10317854896 10735501312 1390947280 14.514 100 66 132 1 1429782080 22937504 15897296
23:48:11 1482 8192000000 10335493280 10748780544 1398145808 14.578 100 73 140 3 1443095184 26676512 18272864
23:49:16 5050 8192000000 10329064992 10748170240 1393739472 14.539 100 55 107 1 1440297856 33365488 13192896
23:50:27 8201 8192000000 10334955040 10758746112 1398768160 14.584 100 50 100 1 1445762480 34101840 12892480
23:51:42 2474 8192000000 10331922960 10751389696 1388841184 14.496 99 53 107 0 1432581664 30293184 13447296
23:52:48 4872 8192000000 10334554112 10749673472 1393356064 14.536 100 63 126 1 1441693392 32792480 15544848
23:54:01 10315 8192000000 10322719856 10747568128 1389535936 14.502 100 31 62 1 1429551392 31592800 8422656
23:55:20 3978 8192000000 10315296928 10718224384 1339748960 14.055 98 72 143 0 1402134752 44892800 17492992
23:56:30 3732 8192000000 10349743632 10755469312 1381489328 14.430 100 77 152 0 1448885248 47605040 19790880
23:57:39 4862 8192000000 10329452960 10752909312 1385527984 14.466 100 51 103 2 1446529376 46938176 14063216
23:58:56 6111 8192000000 10322277024 10746179584 1380098128 14.417 100 41 80 0 1431578048 40596032 10883888
00:00:09 10929 8192000000 10333438288 10756653056 1383246752 14.446 100 48 96 1 1445401280 49078464 13076064
00:01:22 17527 8192000000 10287749168 10717069312 1333726848 14.001 99 28 56 0 1387096288 45152672 8216768
00:02:43 24748 8192000000 10284790384 10719301632 1343960352 14.093 100 23 46 1 1400540736 49565328 7015056
00:04:11 44259 8192000000 10238049136 10686631936 1316773216 13.847 98 9 18 0 1349757680 29995728 2988736
00:06:13 69327 8192000000 10284782928 10720804864 1367222640 14.302 100 29 58 0 1387090960 13554896 6313424
00:08:23 106201 8192000000 10297096848 10739916800 1370113168 14.328 99 20 40 1 1388401056 13097584 5190304
00:11:24 53728 8192000000 10303889056 10744938496 1365511808 14.287 98 16 32 1 1379844560 11073168 3259584
00:13:19 116752 8192000000 10314674480 10763243520 1361677616 14.252 97 18 35 0 1378059056 12387904 3993536
00:16:17 24010 8192000000 10350219248 10787381248 1415486768 14.733 100 34 68 2 1441864896 17674048 8704080
00:17:43 48836 8192000000 10342994896 10808078336 1419468064 14.768 100 30 60 0 1452997632 26141664 7387904
00:20:12 30184 8192000000 10325435424 10807402496 1404732256 14.637 100 11 22 1 1436146304 28833248 2580800
00:21:44 62953 8192000000 10300472160 10779365376 1376854176 14.388 98 16 32 0 1402022800 20779616 4389008
00:23:57 83804 8192000000 10308705408 10770153472 1389088816 14.498 99 18 36 0 1401105600 7819936 4196848
00:26:21 18337 8192000000 10318938368 10782724096 1407930880 14.666 100 28 55 1 1427579840 13141920 6507040
00:27:46 77924 8192000000 10305236640 10777825280 1393531824 14.537 99 13 26 1 1411341824 14341088 3468912
00:30:04 53965 8192000000 10294192112 10762129408 1371525536 14.341 98 14 28 0 1397201024 21943216 3732272
00:32:02 72874 8192000000 10318655200 10777755648 1397453808 14.572 100 11 20 2 1414336608 14417024 2465776
00:34:16 41410 8192000000 10312243824 10764972032 1386568000 14.475 99 15 30 0 1398003088 7904784 3530304
00:36:00 68522 8192000000 10317085904 10767896576 1359260032 14.231 97 22 44 0 1380525232 16275552 4989648
00:38:27 107708 8192000000 10291364464 10744004608 1322872960 13.903 95 21 40 0 1339863344 12757888 4232496
00:41:15 30810 8192000000 10285524400 10737487872 1347445792 14.124 98 19 38 0 1363204784 12023936 3735056
00:42:47 44815 8192000000 10305645360 10745196544 1348907600 14.138 98 20 40 0 1371958576 18567440 4483536
00:44:34 117897 8192000000 10284659728 10737147904 1367453152 14.304 99 19 38 0 1386633056 14652224 4527680
00:47:51 97473 8192000000 10272259088 10724311040 1348845168 14.137 98 19 39 1 1363891840 10939152 4107520
00:50:29 80751 8192000000 10265471040 10729635840 1334735824 14.010 98 15 30 1 1355890464 17272288 3882352
00:52:51 72039 8192000000 10253438944 10717200384 1330055344 13.968 98 19 38 0 1354502736 19762640 4684752
00:55:12 80896 8192000000 10240657328 10689019904 1303771056 13.730 96 15 30 0 1324271920 16526144 3974720
00:57:46 73384 8192000000 10258308288 10707742720 1328278192 13.952 98 13 26 1 1342652832 11455312 2919328
01:00:00 52089 8192000000 10260052640 10715897856 1329753344 13.965 98 13 26 0 1344250352 11566304 2930704
01:01:53 52753 8192000000 10263867008 10700156928 1307455696 13.763 96 31 62 0 1324970048 9872496 7641856
01:03:50 27533 8192000000 10279430912 10720862208 1360779136 14.244 100 32 64 0 1378417280 10707984 6930160
01:05:20 44041 8192000000 10290936496 10737332224 1358416256 14.223 99 33 66 0 1386965920 20965936 7583728
01:07:17 67594 8192000000 10293445376 10746109952 1379661008 14.414 100 35 70 1 1399376048 12037840 7677200
01:09:27 42541 8192000000 10305416208 10750324736 1349414128 14.142 97 30 60 0 1365773968 10335088 6024752
01:11:10 123173 8192000000 10303660048 10750427136 1363526384 14.269 98 28 56 1 1385472080 15490208 6455488
01:14:24 81069 8192000000 10280607904 10754519040 1377228832 14.392 99 8 16 0 1388234912 9237408 1768672
01:17:51 84212 8192000000 10298220944 10767024128 1398789792 14.584 100 8 16 0 1411542608 11257616 1495200
01:20:19 40178 8192000000 10307271664 10777186304 1408823936 14.673 100 22 44 2 1427964608 13950192 5190480
01:22:14 120890 8192000000 10305961712 10784595968 1399156576 14.587 99 13 26 0 1410969456 9059760 2753120
01:25:21 33215 8192000000 10308297184 10766368768 1371198576 14.338 97 24 48 0 1384278192 7958384 5121232
01:27:55 29952 8192000000 10266278176 10703663104 1329668288 13.964 97 39 78 0 1349774528 11294880 8811360
01:29:28 87463 8192000000 10286756336 10742530048 1360051936 14.238 98 14 28 1 1372599216 9837792 2709488
01:32:06 104083 8192000000 10282259424 10741477376 1336741344 14.028 97 23 46 1 1359571424 17468784 5361296
01:34:50 55517 8192000000 10293921120 10743300096 1362265984 14.258 99 10 20 0 1379070288 14961488 1842816
01:36:49 102941 8192000000 9677296736 10039984128 809396064 8.991 59 7 14 0 822576224 12195136 985024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-05-2022 01:40:06 to 16-05-2022 01:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 123 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-05-2022 01:40:22 to 16-05-2022 01:40:24.

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          241015
MON$OLDEST_ACTIVE               241016
MON$OLDEST_SNAPSHOT             241016
MON$NEXT_TRANSACTION            241016
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                       2027616
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:385: 
:386: mscs-test-161.budzdorov.ru    Mon May 16 01:38:45 2022
:387:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:388: 
:389: 
:390: mscs-test-161.budzdorov.ru    Mon May 16 01:38:45 2022
:391:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:392: 
--- end of diff output ---

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

16.05.22 01:40:25. 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-16 01:40:27 No severe PSQL-related problems occured
16.05.22 01:40:29. End of report.