Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2021 01:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15868752 kB
MemAvailable 30724664 kB
Buffers 0 kB
Cached 15799840 kB
SwapCached 2920 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-30 01:37:47
DTS_END                         2021-09-30 01:37:47.0620
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/38310
TRN_ID                          239837
ATT_ID                          651
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: 9353.98 19204 1122477
customer order: creation 172.09 362 20651
customer order: refuse 14.52 387 1742
order to supplier: creation 46.09 1338 5531
order to supplier: removal 3.59 110200 431
invoice (draft): creation 45.66 3603 5479
invoice (draft): removal 54.19 919 6503
invoice accept: apply 41.36 19664 4963
invoice accept: cancel 49.10 898 5892
customer reserve: creation 3824.80 194 458976
customer reserve: removal 4809.18 63 577102
realization accept: apply 82.74 179 9929
realization accept: cancel 82.09 125 9851
payment from customer: creation 59.07 118 7088
payment from customer: removal 5.48 102 657
payment to supplier: creation 48.60 141 5832
payment to supplier: removal 3.14 91 377
service: total inventory turnovers 4.76 1179 571
service: total monetary turnovers 6.66 65 799
service: aggregate perf. data 0.83 23218 99
service: refresh index statistics 0.03 221235 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2021 01:38:20 to 30-09-2021 01:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3546
TEST_TIME 2 3620
TEST_TIME 3 4520
TEST_TIME 4 4508
TEST_TIME 5 5215
TEST_TIME 6 4934
TEST_TIME 7 4813
TEST_TIME 8 4889
TEST_TIME 9 5378
TEST_TIME 10 5470
TEST_TIME 11 6177
TEST_TIME 12 6426
TEST_TIME 13 6814
TEST_TIME 14 7258
TEST_TIME 15 7700
TEST_TIME 16 7550
TEST_TIME 17 7892
TEST_TIME 18 8027
TEST_TIME 19 7971
TEST_TIME 20 8083
TEST_TIME 21 8154
TEST_TIME 22 8303
TEST_TIME 23 8215
TEST_TIME 24 8129
TEST_TIME 25 8404
TEST_TIME 26 8549
TEST_TIME 27 8479
TEST_TIME 28 8696
TEST_TIME 29 8690
TEST_TIME 30 8647
TEST_TIME 31 8593
TEST_TIME 32 8821
TEST_TIME 33 9412
TEST_TIME 34 10686
TEST_TIME 35 10595
TEST_TIME 36 10506
TEST_TIME 37 10481
TEST_TIME 38 10209
TEST_TIME 39 9968
TEST_TIME 40 9952
TEST_TIME 41 9970
TEST_TIME 42 9916
TEST_TIME 43 10012
TEST_TIME 44 9962
TEST_TIME 45 9878
TEST_TIME 46 9769
TEST_TIME 47 9707
TEST_TIME 48 9616
TEST_TIME 49 9509
TEST_TIME 50 9443
TEST_TIME 51 9484
TEST_TIME 52 9411
TEST_TIME 53 9392
TEST_TIME 54 9345
TEST_TIME 55 9317
TEST_TIME 56 9262
TEST_TIME 57 9260
TEST_TIME 58 9223
TEST_TIME 59 9320
TEST_TIME 60 9374
TEST_TIME 61 9410
TEST_TIME 62 9369
TEST_TIME 63 9523
TEST_TIME 64 9518
TEST_TIME 65 9542
TEST_TIME 66 9536
TEST_TIME 67 9499
TEST_TIME 68 9518
TEST_TIME 69 9639
TEST_TIME 70 9577
TEST_TIME 71 9496
TEST_TIME 72 9529
TEST_TIME 73 9529
TEST_TIME 74 9443
TEST_TIME 75 9443
TEST_TIME 76 9458
TEST_TIME 77 9399
TEST_TIME 78 9387
TEST_TIME 79 9384
TEST_TIME 80 9331
TEST_TIME 81 9259
TEST_TIME 82 9172
TEST_TIME 83 9218
TEST_TIME 84 9226
TEST_TIME 85 9279
TEST_TIME 86 9254
TEST_TIME 87 9247
TEST_TIME 88 9337
TEST_TIME 89 9334
TEST_TIME 90 9342
TEST_TIME 91 9353
TEST_TIME 92 9428
TEST_TIME 93 9402
TEST_TIME 94 9433
TEST_TIME 95 9406
TEST_TIME 96 9428
TEST_TIME 97 9509
TEST_TIME 98 9427
TEST_TIME 99 9391
TEST_TIME 100 9329
TEST_TIME 101 9326
TEST_TIME 102 9273
TEST_TIME 103 9271
TEST_TIME 104 9225
TEST_TIME 105 9230
TEST_TIME 106 9199
TEST_TIME 107 9192
TEST_TIME 108 9223
TEST_TIME 109 9222
TEST_TIME 110 9236
TEST_TIME 111 9221
TEST_TIME 112 9204
TEST_TIME 113 9265
TEST_TIME 114 9260
TEST_TIME 115 9270
TEST_TIME 116 9307
TEST_TIME 117 9340
TEST_TIME 118 9298
TEST_TIME 119 9393
TEST_TIME 120 9357

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-09-2021 01:38:24 to 30-09-2021 01:38:27.

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 1548046 1548046 0 0.00 0 30842 6 0 0
doc_list_biud 1548043 1548043 0 0.00 0 46479 30 0 0
sp_add_invoice_to_stock 5064 4963 101 1.99 39 188459 19665 0 101
sp_cancel_adding_invoice 5892 5892 0 0.00 5 63774 899 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 19113 388 0 0
sp_cancel_customer_reserve 577102 577102 0 0.00 1 46481 64 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 3941 103 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 1996 92 0 0
sp_cancel_supplier_invoice 6507 6503 4 0.06 2 32969 920 0 4
sp_cancel_supplier_order 431 431 0 0.00 7 1083638 110200 0 0
sp_cancel_write_off 9851 9851 0 0.00 1 23836 126 0 0
sp_client_order 20651 20651 0 0.00 3 24359 363 0 0
sp_customer_reserve 458976 458976 0 0.00 0 115381 195 0 0
sp_fill_shopping_cart 31647 31647 0 0.00 1 14043 40 0 0
sp_fill_shopping_cart_clo_res 458970 458970 0 0.00 0 15461 14 0 0
sp_get_clo_for_invoice 4957 4957 0 0.00 0 30803 292 0 0
sp_kill_cost_storno 16777 16777 0 0.00 0 8156 64 0 0
sp_kill_qstorno_ret_qs2qd 1170989 1170989 0 0.00 0 46474 35 0 0
sp_kill_qty_storno 616406 616406 0 0.00 0 46478 75 0 0
sp_lock_selected_doc 20584 20584 0 0.00 0 8086 5 0 0
sp_make_cost_storno 27806 27806 0 0.00 0 30840 55 0 0
sp_multiply_rows_for_pdistr 27805 27805 0 0.00 0 14810 31 0 0
sp_multiply_rows_for_qdistr 20651 20651 0 0.00 0 16151 159 0 0
sp_pay_from_customer 7088 7088 0 0.00 4 12427 118 0 0
sp_pay_to_supplier 5832 5832 0 0.00 5 30847 142 0 0
sp_payment_common 12920 12920 0 0.00 1 18169 27 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 19106 352 0 0
sp_qd_handle_on_invoice_upd_sts 10849 10849 0 0.00 0 23822 372 0 0
sp_qd_handle_on_reserve_upd_sts 9928 9928 0 0.00 0 10815 65 0 0
sp_reserve_write_off 9929 9929 0 0.00 6 14078 180 0 0
sp_supplier_invoice 5479 5479 0 0.00 7 90991 3604 0 0
sp_supplier_order 5531 5531 0 0.00 6 26504 1339 0 0
srv_aggregate_perf_data 100 99 1 1.00 2144 309094 23218 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1699 156712 44148 0 0
srv_find_qd_qs_mism 1075256 1075256 0 0.00 0 16463 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 33 135348 1180 0 0
srv_make_money_saldo 799 799 0 0.00 0 28576 66 0 0
srv_recalc_idx_stat 4 4 0 0.00 75864 401864 221235 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 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 4963 4963 0 0.00 0 13719 190 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 22263 225 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 9366 28 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 3189 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 7194 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 292 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 690 21 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 27435 1758 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 4582 8 0 0
t$perf-norm:sp_client_order 20651 20651 0 0.00 0 15971 22 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 5646 12 0 0
t$perf-norm:sp_pay_from_customer 7088 7088 0 0.00 0 14618 19 0 0
t$perf-norm:sp_pay_to_supplier 5832 5832 0 0.00 0 14614 16 0 0
t$perf-norm:sp_reserve_write_off 9930 9930 0 0.00 0 6748 13 0 0
t$perf-norm:sp_supplier_invoice 5479 5479 0 0.00 0 6633 27 0 0
t$perf-norm:sp_supplier_order 5531 5531 0 0.00 0 10061 23 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1403 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 415 7 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 1540 27 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 4743 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 39 12 0 0
v_add_invoice_to_stock 10114 9912 202 1.99 0 4473 21 0 202
v_all_customers 37518 37518 0 0.00 0 18 1 0 0
v_all_suppliers 30578 30578 0 0.00 0 20 1 0 0
v_all_wares 41302 41302 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 178 6 0 0
v_cancel_client_order 3484 3484 0 0.00 0 1982 4 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 2891 8 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 5719 21 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 1985 10 0 0
v_cancel_supplier_invoice 752 744 8 1.06 0 673 16 0 8
v_cancel_supplier_order 862 862 0 0.00 0 289 3 0 0
v_cancel_write_off 2008 2008 0 0.00 0 1421 14 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 7425 3 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 6225 18 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 6503 4 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 252 3 0 0
v_max_non_paid_realizn 3929 3929 0 0.00 0 4179 5 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 3241 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 391 3 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 5164 7 0 0
v_min_non_paid_invoice 5832 5832 0 0.00 0 18164 16 0 0
v_min_non_paid_realizn 7088 7088 0 0.00 0 7550 17 0 0
v_our_firm 10102 10102 0 0.00 0 14 1 0 0
v_reserve_write_off 19856 19856 0 0.00 0 4197 7 0 0
x_lock_depdocs_on_canc_invoice 12023 12023 0 0.00 0 29911 152 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 12919 386 0 0
x_make_qstorno_1000_1200 5531 5531 0 0.00 1 26489 1246 0 0
x_make_qstorno_1200_2000 5479 5479 0 0.00 3 90975 3465 0 0
x_make_qstorno_2100_3300 458976 458976 0 0.00 0 115380 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2021 01:38:28 to 30-09-2021 01:38:32.

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:07:50 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:52 18074 8192000000 10369911584 10688999424 1421511648 14.786 100 86 172 0 1456108208 6038992 28557568
23:10:15 28592 8192000000 10440474640 10772721664 1488893760 15.379 100 82 164 1 1523336528 7940864 26501904
23:11:47 21178 8192000000 10487486928 10825965568 1535304672 15.783 100 82 164 0 1570795168 9549920 25940576
23:13:12 13657 8192000000 10561436368 10915028992 1606529232 16.395 100 72 144 1 1639132000 10647632 21955136
23:14:29 8221 8192000000 10641993456 11015028736 1701861168 17.201 100 68 134 0 1731706576 10224640 19620768
23:15:39 5177 8192000000 10708673296 11080757248 1765420432 17.729 100 85 170 0 1802755504 14065552 23269520
23:16:46 8822 8192000000 10744634880 11128643584 1806633104 18.068 100 78 155 2 1841268176 13922272 20712800
23:17:57 1996 8192000000 10791396992 11177000960 1853523536 18.451 100 78 156 0 1886749072 10482608 22742928
23:19:01 1609 8192000000 10830883488 11221647360 1885335904 18.708 100 95 191 2 1925574512 13218944 27019664
23:20:04 5932 8192000000 10812803456 11219140608 1889267312 18.740 100 68 137 0 1916539200 6915376 20356512
23:21:10 9072 8192000000 10820029616 11230986240 1893316400 18.772 100 35 70 1 1909563696 6726928 9520368
23:22:21 8633 8192000000 10813103840 11242749952 1900024624 18.826 100 16 32 0 1910067920 5564832 4478464
23:23:31 7999 8192000000 10822014032 11255496704 1912063056 18.923 100 27 54 4 1926838320 6875520 7899744
23:24:40 10703 8192000000 10821897088 11258753024 1917808256 18.969 100 20 40 1 1929660240 5821376 6030608
23:25:54 36917 8192000000 10819534960 11264090112 1918164112 18.972 100 20 40 2 1929319648 5243792 5911744
23:27:35 6362 8192000000 10804391408 11232890880 1842066592 18.358 96 33 66 0 1857728272 5805728 9855952
23:28:44 4451 8192000000 10775170240 11189436416 1804150832 18.048 96 33 64 1 1819229712 5195904 9882976
23:29:49 8043 8192000000 10727991328 11150364672 1810789232 18.102 100 24 48 1 1827949344 10726656 6433456
23:30:59 37233 8192000000 10678894320 11097649152 1607645552 16.405 89 18 36 1 1618168544 4975968 5547024
23:32:39 5017 8192000000 10737240448 11157786624 1827585008 18.240 100 25 50 1 1840604288 5241584 7777696
23:33:45 5905 8192000000 10766859984 11197583360 1857077440 18.480 100 34 68 1 1873658224 6171728 10409056
23:34:57 6697 8192000000 10775290304 11208572928 1866147760 18.553 100 27 54 1 1880660304 6617152 7895392
23:36:04 23243 8192000000 10776353408 11214688256 1877134768 18.642 100 32 62 1 1890916752 4420704 9361280
23:37:30 5236 8192000000 10814573504 11242303488 1903754592 18.856 100 33 67 0 1919551184 5488672 10307920
23:38:41 12167 8192000000 10867876784 11282964480 1927186128 19.044 100 60 119 1 1953091328 9415696 16489504
23:39:56 10023 8192000000 10882607856 11300458496 1941275616 19.157 100 57 114 1 1968489936 10255344 16958976
23:41:08 21656 8192000000 10878395808 11316453376 1949164416 19.220 100 38 74 2 1970268400 10011920 11092064
23:42:42 9614 8192000000 10907015552 11335344128 1968447536 19.373 100 47 93 1 1992097568 9901536 13748496
23:43:59 13537 8192000000 10905743968 11345051648 1976493264 19.437 100 38 76 2 2000260832 12874544 10893024
23:45:16 27371 8192000000 10939995360 11375681536 1999740768 19.621 100 52 104 2 2033238416 18225328 15272320
23:46:51 5301 8192000000 10929659600 11367493632 1997303296 19.601 100 53 106 2 2030461920 17792160 15366464
23:48:09 3330 8192000000 10959009632 11376799744 1986870624 19.519 99 74 148 2 2029056000 20546400 21638976
23:49:17 3618 8192000000 10944959552 11379109888 1994611152 19.580 100 64 124 1 2037343840 23642704 19089984
23:50:24 4936 8192000000 10963640832 11387551744 1998260208 19.609 100 75 148 2 2048140624 25804384 24076032
23:51:36 3533 8192000000 10935698416 11353468928 1971722384 19.399 100 76 150 0 2017571680 24375696 21473600
23:52:49 4894 8192000000 10905721232 11347025920 1959086176 19.299 100 47 93 0 1996710464 23557072 14067216
23:54:01 7298 8192000000 10913541136 11347365888 1955082896 19.267 100 54 107 3 2001640592 29613296 16944400
23:55:15 8226 8192000000 10879650336 11317395456 1929224784 19.061 100 46 92 0 1968995424 25928944 13841696
23:56:30 3759 8192000000 10884681680 11290664960 1907071792 18.883 99 76 148 0 1957312464 28569392 21671280
23:57:46 5180 8192000000 10921591424 11338276864 1953722976 19.256 100 72 145 1 2006826928 31722080 21381872
23:58:55 2499 8192000000 10969203840 11381850112 1984310976 19.499 100 91 182 0 2048064192 36055952 27697264
00:00:00 5043 8192000000 10929240432 11358466048 1964409424 19.341 100 60 119 0 2016690784 33185472 19095888
00:01:15 9013 8192000000 10948874784 11376533504 1970070656 19.386 100 64 128 0 2033883744 43039696 20773392
00:02:37 1697 8192000000 10997992032 11417206784 2001388880 19.634 100 90 180 1 2082151584 52812864 27949840
00:03:43 24629 8192000000 10960321056 11390971904 1968724864 19.375 99 55 110 1 2030370144 44056352 17588928
00:05:10 3834 8192000000 10968343296 11391868928 1981786992 19.479 100 65 127 1 2052140816 50815168 19538656
00:06:17 13157 8192000000 10972578496 11402498048 1971096928 19.394 99 46 92 3 2037274288 50646160 15531200
00:07:35 12283 8192000000 10987564560 11428622336 2008673696 19.691 100 43 84 2 2073665904 51492112 13500096
00:08:48 14912 8192000000 10998467808 11442180096 2002023008 19.639 100 36 72 2 2080100672 64468688 13608976
00:10:08 23890 8192000000 10957115328 11419836416 1964485120 19.342 99 24 48 0 2032281104 58864656 8931328
00:11:32 49807 8192000000 10907072560 11366932480 1947388224 19.206 98 11 22 0 1978281840 27708192 3185424
00:13:28 52020 8192000000 10922165664 11378745344 1972611040 19.406 99 40 80 0 1996944672 13431568 10902064
00:15:41 90934 8192000000 10919829712 11373789184 1949325488 19.221 98 33 67 0 1972907328 13426640 10155200
00:18:13 76205 8192000000 10924293024 11384172544 1985729632 19.510 100 39 76 1 2008533632 11360720 11443280
00:20:31 87905 8192000000 10911947104 11389980672 1985683472 19.510 100 6 12 0 2003499280 16275616 1540192
00:23:00 52506 8192000000 10913175504 11394027520 1990876864 19.551 100 20 40 0 2006917104 10159744 5880496
00:24:53 140214 8192000000 10917498736 11403878400 1957991056 19.290 98 15 28 1 1977796000 15069792 4735152
00:28:39 80563 8192000000 10908816080 11395289088 1982612528 19.485 99 10 20 0 1995516288 10099392 2804368
00:31:00 98718 8192000000 10915580768 11412365312 2003673056 19.652 100 10 20 0 2016174480 9692384 2809040
00:33:41 63499 8192000000 10923674608 11414478848 2013559808 19.730 100 22 44 1 2028218800 8942608 5716384
00:35:45 62292 8192000000 10911194848 11400003584 1975239296 19.427 99 17 34 0 1992245168 11988736 5017136
00:37:50 63398 8192000000 10890194128 11355754496 1894429072 18.781 95 14 28 0 1914761344 16474256 3858016
00:40:04 38345 8192000000 10926410944 11400806400 1973272064 19.411 99 20 41 0 1998362560 18971408 6119088
00:41:43 74810 8192000000 10887197200 11367014400 1889850544 18.745 95 14 28 0 1907373888 13740560 3782784
00:44:02 92220 8192000000 10909839744 11377917952 1886758880 18.720 95 15 30 1 1903393504 12553424 4081200
00:46:34 49433 8192000000 10882771936 11334860800 1868789184 18.574 95 20 40 0 1888173888 14076992 5307712
00:48:29 106446 8192000000 10896565024 11372625920 1946562752 19.199 99 34 68 0 1970877344 14913536 9401056
00:51:16 44468 8192000000 10890271600 11351306240 1930626672 19.072 98 38 74 3 1950235744 9448976 10160096
00:53:07 76526 8192000000 10845628624 11326423040 1861387056 18.515 95 9 18 0 1876044784 12050624 2607104
00:55:24 43334 8192000000 10843199904 11311013888 1912093600 18.923 99 18 36 1 1922530544 6166528 4270416
00:57:07 51436 8192000000 10823263568 11290189824 1895336880 18.789 98 20 40 0 1912433536 11532656 5564000
00:58:59 72672 8192000000 10846492960 11328020480 1886779408 18.720 97 12 24 1 1904682064 14346656 3556000
01:01:17 73815 8192000000 10860947280 11343110144 1913037728 18.931 99 11 22 1 1926622656 10576128 3008800
01:03:36 37426 8192000000 10870645760 11322511360 1892277920 18.764 97 27 54 1 1916462640 16689776 7494944
01:05:13 62481 8192000000 10876054672 11357032448 1951354896 19.237 100 20 40 0 1972211952 15200224 5656832
01:07:27 40857 8192000000 10872548288 11329028096 1893418864 18.773 97 29 57 4 1914050848 13033120 7598864
01:09:08 69578 8192000000 10862110992 11334799360 1896386736 18.797 97 14 28 0 1913982544 14293424 3302384
01:11:18 80243 8192000000 10890661088 11352756224 1934637728 19.104 99 19 39 2 1954611136 14584304 5389104
01:13:45 156711 8192000000 10915967024 11390111744 1984055616 19.497 100 26 52 1 2009794240 18326336 7412288
01:17:34 23485 8192000000 10890366544 11354333184 1962872672 19.329 100 25 50 2 1979587744 9942128 6772944
01:18:59 60223 8192000000 10889976224 11371028480 1975990160 19.433 100 8 16 0 1987623040 9804960 1827920
01:21:03 48249 8192000000 10915820960 11387662336 1985424720 19.508 100 16 32 0 2004023376 13356800 5241856
01:22:54 66272 8192000000 10908506640 11389083648 1990067328 19.544 100 14 28 1 2007114256 13032656 4014272
01:25:02 58695 8192000000 10909945152 11397677056 1966313648 19.356 99 18 36 0 1990665344 18443472 5908224
01:27:04 57425 8192000000 10884794288 11365949440 1890257600 18.748 95 22 44 1 1905631264 8926640 6447024
01:29:09 52349 8192000000 10879531776 11349737472 1924604400 19.024 98 22 44 1 1940810448 9862576 6343472
01:31:06 28813 8192000000 10908851856 11379838976 1976611440 19.438 100 33 67 2 1997821376 12132976 9076960
01:32:54 93171 8192000000 10875029024 11369877504 1916089088 18.955 97 13 26 0 1935836144 15627648 4119408
01:35:28 35737 8192000000 10922384096 11375239168 1959137696 19.299 99 45 88 2 1979896688 9122160 11636832
01:37:05 44966 8192000000 9070788832 9287258112 222029968 2.638 12 3 6 0 223919952 1481600 408384

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-09-2021 01:38:34 to 30-09-2021 01:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 101 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2021 01:38:50 to 30-09-2021 01:38:52.

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          240166
MON$OLDEST_ACTIVE               240167
MON$OLDEST_SNAPSHOT             240167
MON$NEXT_TRANSACTION            240167
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                       2045856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33504 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33504 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33504 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33504 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Sep 30 01:37:52 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Sep 30 01:37:52 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

30.09.21 01:38:53. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-30 01:38:55 No severe PSQL-related problems occured
30.09.21 01:38:56. End of report.