Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.07.2022 19: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 16073664 kB
MemAvailable 31100968 kB
Buffers 0 kB
Cached 15657436 kB
SwapCached 2488 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-07-17 19:38:27
DTS_END                         2022-07-17 19:38:27.4550
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/47800
TRN_ID                          251685
ATT_ID                          660
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9270.71 21686 1112485
customer order: creation 187.01 362 22441
customer order: refuse 16.37 359 1964
order to supplier: creation 50.27 1262 6032
order to supplier: removal 3.28 95814 393
invoice (draft): creation 48.83 3504 5860
invoice (draft): removal 49.95 705 5994
invoice accept: apply 45.76 18383 5491
invoice accept: cancel 45.64 845 5477
customer reserve: creation 4061.67 190 487400
customer reserve: removal 4450.27 56 534032
realization accept: apply 90.47 183 10856
realization accept: cancel 80.19 104 9623
payment from customer: creation 64.49 115 7739
payment from customer: removal 6.01 81 721
payment to supplier: creation 52.93 128 6352
payment to supplier: removal 4.08 60 489
service: total inventory turnovers 5.52 1056 662
service: total monetary turnovers 7.08 33 850
service: aggregate perf. data 0.88 24776 105
service: refresh index statistics 0.03 285703 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-07-2022 19:39:50 to 17-07-2022 19:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6425
TEST_TIME 2 5916
TEST_TIME 3 6265
TEST_TIME 4 6178
TEST_TIME 5 6961
TEST_TIME 6 7105
TEST_TIME 7 7771
TEST_TIME 8 7497
TEST_TIME 9 7284
TEST_TIME 10 7169
TEST_TIME 11 7521
TEST_TIME 12 7542
TEST_TIME 13 7537
TEST_TIME 14 7748
TEST_TIME 15 7878
TEST_TIME 16 7774
TEST_TIME 17 7714
TEST_TIME 18 7671
TEST_TIME 19 8116
TEST_TIME 20 8321
TEST_TIME 21 8656
TEST_TIME 22 8547
TEST_TIME 23 9056
TEST_TIME 24 9130
TEST_TIME 25 9023
TEST_TIME 26 9166
TEST_TIME 27 9286
TEST_TIME 28 9211
TEST_TIME 29 9540
TEST_TIME 30 9832
TEST_TIME 31 9866
TEST_TIME 32 10142
TEST_TIME 33 10235
TEST_TIME 34 10424
TEST_TIME 35 10350
TEST_TIME 36 10289
TEST_TIME 37 10167
TEST_TIME 38 10080
TEST_TIME 39 9879
TEST_TIME 40 9865
TEST_TIME 41 9823
TEST_TIME 42 9730
TEST_TIME 43 9734
TEST_TIME 44 9694
TEST_TIME 45 9654
TEST_TIME 46 9608
TEST_TIME 47 9715
TEST_TIME 48 9577
TEST_TIME 49 9555
TEST_TIME 50 9451
TEST_TIME 51 9514
TEST_TIME 52 9733
TEST_TIME 53 9679
TEST_TIME 54 9771
TEST_TIME 55 9722
TEST_TIME 56 9721
TEST_TIME 57 9782
TEST_TIME 58 9807
TEST_TIME 59 9845
TEST_TIME 60 9820
TEST_TIME 61 9917
TEST_TIME 62 9852
TEST_TIME 63 9930
TEST_TIME 64 9960
TEST_TIME 65 9933
TEST_TIME 66 9805
TEST_TIME 67 9801
TEST_TIME 68 9754
TEST_TIME 69 9713
TEST_TIME 70 9724
TEST_TIME 71 9661
TEST_TIME 72 9602
TEST_TIME 73 9561
TEST_TIME 74 9541
TEST_TIME 75 9560
TEST_TIME 76 9474
TEST_TIME 77 9465
TEST_TIME 78 9469
TEST_TIME 79 9556
TEST_TIME 80 9570
TEST_TIME 81 9550
TEST_TIME 82 9552
TEST_TIME 83 9560
TEST_TIME 84 9612
TEST_TIME 85 9593
TEST_TIME 86 9597
TEST_TIME 87 9630
TEST_TIME 88 9628
TEST_TIME 89 9687
TEST_TIME 90 9683
TEST_TIME 91 9605
TEST_TIME 92 9526
TEST_TIME 93 9500
TEST_TIME 94 9446
TEST_TIME 95 9373
TEST_TIME 96 9372
TEST_TIME 97 9441
TEST_TIME 98 9482
TEST_TIME 99 9453
TEST_TIME 100 9483
TEST_TIME 101 9487
TEST_TIME 102 9459
TEST_TIME 103 9522
TEST_TIME 104 9487
TEST_TIME 105 9500
TEST_TIME 106 9511
TEST_TIME 107 9509
TEST_TIME 108 9522
TEST_TIME 109 9511
TEST_TIME 110 9444
TEST_TIME 111 9475
TEST_TIME 112 9417
TEST_TIME 113 9418
TEST_TIME 114 9388
TEST_TIME 115 9353
TEST_TIME 116 9344
TEST_TIME 117 9306
TEST_TIME 118 9315
TEST_TIME 119 9309
TEST_TIME 120 9272

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-07-2022 19:39:53 to 17-07-2022 19:39:57.

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 1564384 1564384 0 0.00 0 18462 7 0 0
doc_list_biud 1564383 1564383 0 0.00 0 30453 25 0 0
sp_add_invoice_to_stock 5665 5491 174 3.07 21 183766 18384 0 174
sp_cancel_adding_invoice 5477 5477 0 0.00 5 59878 845 0 0
sp_cancel_client_order 1964 1964 0 0.00 3 13428 359 0 0
sp_cancel_customer_reserve 534032 534032 0 0.00 0 29383 56 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 2 4867 81 0 0
sp_cancel_pay_to_supplier 489 489 0 0.00 2 2114 61 0 0
sp_cancel_supplier_invoice 6012 5994 18 0.29 2 24637 706 0 18
sp_cancel_supplier_order 393 393 0 0.00 7 723573 95814 0 0
sp_cancel_write_off 9623 9623 0 0.00 1 10217 104 0 0
sp_client_order 22441 22441 0 0.00 3 33284 362 0 0
sp_customer_reserve 487400 487400 0 0.00 0 101605 190 0 0
sp_fill_shopping_cart 34304 34304 0 0.00 1 16765 39 0 0
sp_fill_shopping_cart_clo_res 487390 487390 0 0.00 0 16996 12 0 0
sp_get_clo_for_invoice 5481 5481 0 0.00 0 25786 253 0 0
sp_kill_cost_storno 16310 16310 0 0.00 0 9884 52 0 0
sp_kill_qstorno_ret_qs2qd 1084074 1084074 0 0.00 0 29380 30 0 0
sp_kill_qty_storno 573820 573820 0 0.00 0 30452 66 0 0
sp_lock_selected_doc 22539 22539 0 0.00 0 5949 6 0 0
sp_make_cost_storno 30428 30428 0 0.00 0 12644 48 0 0
sp_multiply_rows_for_pdistr 30427 30427 0 0.00 0 9596 28 0 0
sp_multiply_rows_for_qdistr 22441 22441 0 0.00 0 11861 145 0 0
sp_pay_from_customer 7739 7739 0 0.00 4 13600 115 0 0
sp_pay_to_supplier 6352 6352 0 0.00 4 11914 128 0 0
sp_payment_common 14090 14090 0 0.00 1 13528 24 0 0
sp_qd_handle_on_cancel_clo 1964 1964 0 0.00 0 13420 329 0 0
sp_qd_handle_on_invoice_upd_sts 10958 10958 0 0.00 0 30450 332 0 0
sp_qd_handle_on_reserve_upd_sts 10856 10856 0 0.00 0 19993 66 0 0
sp_reserve_write_off 10856 10856 0 0.00 6 20005 184 0 0
sp_supplier_invoice 5860 5860 0 0.00 6 92975 3505 0 0
sp_supplier_order 6032 6032 0 0.00 5 36116 1263 0 0
srv_aggregate_perf_data 106 105 1 0.94 2423 602125 24777 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1902 141514 41571 0 0
srv_find_qd_qs_mism 1061469 1061469 0 0.00 0 18458 6 0 0
srv_make_invnt_saldo 662 662 0 0.00 17 64081 1057 0 0
srv_make_money_saldo 850 850 0 0.00 1 1398 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 148774 379090 285703 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 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 5491 5491 0 0.00 0 12157 177 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 6216 134 0 0
t$perf-norm:sp_cancel_client_order 1964 1964 0 0.00 0 2963 18 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 4186 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 2653 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 489 489 0 0.00 0 1202 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1080 12 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 33687 1895 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1823 8 0 0
t$perf-norm:sp_client_order 22441 22441 0 0.00 0 15412 19 0 0
t$perf-norm:sp_customer_reserve 1185 1185 0 0.00 0 2632 11 0 0
t$perf-norm:sp_pay_from_customer 7739 7739 0 0.00 0 7939 12 0 0
t$perf-norm:sp_pay_to_supplier 6352 6352 0 0.00 0 16171 16 0 0
t$perf-norm:sp_reserve_write_off 10856 10856 0 0.00 0 11238 12 0 0
t$perf-norm:sp_supplier_invoice 5860 5860 0 0.00 0 4357 17 0 0
t$perf-norm:sp_supplier_order 6032 6032 0 0.00 0 11217 23 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 204 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 13897 208 0 0
t$perf-norm:srv_make_invnt_saldo 662 662 0 0.00 0 5453 30 0 0
t$perf-norm:srv_make_money_saldo 850 850 0 0.00 0 1926 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 11310 10962 348 3.07 0 5727 16 0 348
v_all_customers 40696 40696 0 0.00 0 51 1 0 0
v_all_suppliers 32214 32214 0 0.00 0 42 1 0 0
v_all_wares 44882 44882 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 3431 14 0 0
v_cancel_client_order 3928 3928 0 0.00 0 2185 6 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 4860 10 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 951 4 0 0
v_cancel_payment_to_supplier 978 978 0 0.00 0 2064 7 0 0
v_cancel_supplier_invoice 882 846 36 4.08 0 2037 16 0 36
v_cancel_supplier_order 786 786 0 0.00 0 3901 15 0 0
v_cancel_write_off 2040 2040 0 0.00 0 3195 19 0 0
v_max_id_clo_ord 6027 6027 0 0.00 0 2005 2 0 0
v_max_id_clo_res 1185 1185 0 0.00 0 4830 18 0 0
v_max_id_ord_sup 5836 5836 0 0.00 0 5273 4 0 0
v_max_non_paid_invoice 2107 2107 0 0.00 0 2370 4 0 0
v_max_non_paid_realizn 4231 4231 0 0.00 0 4686 5 0 0
v_min_id_clo_ord 6027 6027 0 0.00 0 3916 9 0 0
v_min_id_clo_res 1185 1185 0 0.00 0 4322 14 0 0
v_min_id_ord_sup 5836 5836 0 0.00 0 6495 8 0 0
v_min_non_paid_invoice 6351 6351 0 0.00 0 8453 14 0 0
v_min_non_paid_realizn 7739 7739 0 0.00 0 13526 17 0 0
v_our_firm 11202 11202 0 0.00 0 30 1 0 0
v_reserve_write_off 21712 21712 0 0.00 0 5606 6 0 0
x_lock_depdocs_on_canc_invoice 11048 11048 0 0.00 0 28679 125 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 15204 381 0 0
x_make_qstorno_1000_1200 6032 6032 0 0.00 1 36106 1177 0 0
x_make_qstorno_1200_2000 5860 5860 0 0.00 3 92962 3349 0 0
x_make_qstorno_2100_3300 487400 487400 0 0.00 0 101603 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-07-2022 19:39:58 to 17-07-2022 19:40:02.

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
17:08:31 3 8192000000 8803500832 8816332800 9115888 0.111 3 2 3 0 9258304 67472 74944
17:09:31 33036 8192000000 9922644560 10235584512 1001927888 10.897 100 89 178 0 1032174752 6860288 23386576
17:11:10 26129 8192000000 9964870464 10290147328 1047200400 11.334 100 93 186 1 1080956368 10034448 23721520
17:12:41 13450 8192000000 9985850624 10318708736 1068911808 11.542 100 82 164 0 1100127072 10997264 20218000
17:14:00 8527 8192000000 10027916976 10374725632 1116336304 11.992 100 83 166 0 1147120048 10627616 20156128
17:15:10 6628 8192000000 10087558560 10448338944 1178046096 12.572 100 75 148 0 1206882672 11610544 17226032
17:16:20 2305 8192000000 10127552976 10497404928 1213044944 12.897 100 81 160 2 1243889200 13033616 17810640
17:17:27 1171 8192000000 10165752592 10537533440 1251286128 13.250 100 87 174 0 1284454912 13978080 19190704
17:18:30 1036 8192000000 10196317872 10577379328 1280621040 13.519 100 90 180 1 1313694896 12014656 21059200
17:19:32 7494 8192000000 10200981424 10590687232 1295432512 13.654 100 69 138 0 1323295744 12672512 15190720
17:20:41 2713 8192000000 10197619616 10599542784 1302311392 13.716 100 53 104 0 1320874192 6451776 12111024
17:21:45 8277 8192000000 10193898560 10606239744 1309038112 13.777 100 13 26 0 1317896624 6172560 2685952
17:22:56 13786 8192000000 10203730688 10621362176 1320140960 13.878 100 8 15 1 1327792640 5693424 1958256
17:24:14 38933 8192000000 10219195504 10640941056 1325883904 13.930 100 23 46 0 1336819344 5848080 5087360
17:25:56 5308 8192000000 10230629312 10651721728 1335187984 14.014 100 30 60 0 1348500976 6151472 7161520
17:27:04 9230 8192000000 10221256128 10640781312 1325127264 13.923 99 10 19 0 1332552768 5487136 1938368
17:28:15 59211 8192000000 10211537696 10615558144 1294884816 13.649 97 36 70 0 1308472512 5362704 8224992
17:30:15 5728 8192000000 10155955024 10560421888 1256932608 13.302 99 22 44 2 1267028656 5824656 4271392
17:31:23 6064 8192000000 10140587712 10543448064 1242505440 13.169 99 24 48 1 1253796992 5848816 5442736
17:32:30 9945 8192000000 10137666320 10539782144 1237423840 13.123 99 18 36 0 1246766368 5544960 3797568
17:33:40 51587 8192000000 10167671408 10575593472 1274026976 13.458 100 20 40 0 1284281856 5658864 4596016
17:35:33 7213 8192000000 10183252352 10594344960 1289384176 13.599 100 28 56 0 1301817696 5870880 6562640
17:36:45 8365 8192000000 10191564336 10606141440 1302465440 13.718 100 12 23 0 1310354912 5500320 2389152
17:38:01 73253 8192000000 10220194560 10633527296 1319636608 13.873 100 36 67 0 1332271888 5138896 7496384
17:40:25 14922 8192000000 10265936448 10680926208 1357880688 14.218 100 50 97 0 1377630176 9059712 10689776
17:41:43 10363 8192000000 10273924256 10697039872 1370471728 14.331 100 37 74 1 1388120528 8710848 8937952
17:43:02 8032 8192000000 10291200064 10705195008 1366930848 14.300 99 54 106 2 1390610320 11183152 12496320
17:44:14 16389 8192000000 10294312816 10715791360 1379546176 14.412 100 58 112 1 1406501392 13967120 12988096
17:45:33 4317 8192000000 10297457888 10726379520 1388410416 14.492 100 42 84 0 1411346128 12716240 10219472
17:46:47 12525 8192000000 10328844672 10750914560 1404304544 14.633 100 62 122 1 1434532192 16693712 13533936
17:48:03 5809 8192000000 10322276192 10749571072 1395052256 14.551 99 51 101 1 1427776432 20670624 12053552
17:49:10 10747 8192000000 10311955088 10733715456 1388703232 14.494 99 50 99 1 1421791824 20802272 12286320
17:50:24 4373 8192000000 10329042656 10745397248 1388172256 14.490 99 69 137 2 1425062976 20839280 16051440
17:51:33 10515 8192000000 10309791280 10728251392 1373348000 14.357 99 59 116 1 1411171392 24339232 13484160
17:52:53 8906 8192000000 10284082000 10700005376 1345602992 14.108 99 47 95 0 1382087984 24195104 12289888
17:54:06 5420 8192000000 10292671824 10705895424 1333967200 14.003 97 59 116 1 1375041872 27762592 13312080
17:55:17 7136 8192000000 10287626192 10702839808 1352314272 14.168 99 45 90 0 1390687840 26947920 11425648
17:56:27 1901 8192000000 10299227232 10702282752 1349412288 14.142 100 78 155 1 1400638224 32187376 19038560
17:57:36 2575 8192000000 10286991968 10688794624 1336259472 14.024 100 76 148 1 1391915424 38249024 17406928
17:58:45 2277 8192000000 10294652944 10697064448 1338954256 14.048 99 81 160 1 1390094016 30994416 20145344
17:59:53 2851 8192000000 10314596032 10719502336 1368750640 14.316 100 78 154 1 1416500976 28532240 19218096
18:01:00 2240 8192000000 10315689648 10719977472 1370479168 14.331 100 76 154 2 1419353568 29991472 18882928
18:02:04 9498 8192000000 10314761552 10738372608 1382187488 14.436 100 48 97 0 1424650800 29184720 13278592
18:03:25 15225 8192000000 10309399888 10734272512 1368967808 14.318 99 44 88 0 1409185760 28631136 11586816
18:04:44 14077 8192000000 10319153504 10750803968 1387729264 14.486 100 26 50 0 1425751568 31280272 6742032
18:06:07 16969 8192000000 10310032912 10738851840 1376762720 14.388 100 27 54 1 1414842160 30398304 7681136
18:07:26 20969 8192000000 10310766256 10747342848 1376960528 14.389 100 20 40 1 1410814336 28448800 5405008
18:09:12 30069 8192000000 10306365552 10754215936 1368503744 14.314 99 15 30 0 1400155568 27899520 3752304
18:10:43 37317 8192000000 10303308576 10735329280 1357129216 14.212 98 42 82 0 1376217648 9168960 9919472
18:12:21 84140 8192000000 10280158288 10724605952 1333477552 13.999 96 5 10 0 1354745952 20197248 1071152
18:15:03 41036 8192000000 10306389600 10741350400 1386126096 14.471 100 47 89 1 1404949376 7889216 10934064
18:16:45 95817 8192000000 10304693568 10748583936 1387602416 14.484 100 29 58 1 1402613728 8406432 6604880
18:19:25 40367 8192000000 10303500368 10758557696 1386805664 14.477 99 32 64 1 1407176000 12852896 7517440
18:21:22 3583 8192000000 10310930528 10769592320 1402820240 14.620 100 19 39 0 1421104816 13640976 4643600
18:22:26 27411 8192000000 10332696496 10766004224 1390757072 14.513 99 47 92 0 1413902976 11881344 11264560
18:23:55 25559 8192000000 10327277600 10779738112 1414591008 14.725 100 24 48 0 1437211824 16873216 5747600
18:25:29 141514 8192000000 10288525520 10745049088 1300459856 13.699 93 14 28 1 1318885136 15000800 3424480
18:29:26 69919 8192000000 10285668432 10758107136 1377092784 14.391 100 8 16 0 1402393088 22698592 2601712
18:31:51 17312 8192000000 10292847552 10732462080 1348757664 14.136 97 48 96 0 1368711488 8848384 11105440
18:33:57 46339 8192000000 10295189136 10748399616 1386718400 14.477 100 28 55 0 1406424816 13766256 5940160
18:35:46 54837 8192000000 10271346608 10724028416 1300622848 13.701 94 23 46 0 1325465344 19175920 5666576
18:37:53 37000 8192000000 10288852272 10719924224 1360816336 14.245 100 31 62 0 1380178192 12633168 6728688
18:39:31 56207 8192000000 10256786112 10707480576 1310571840 13.791 96 13 25 0 1324023680 11184976 2266864
18:41:30 122091 8192000000 10265397584 10709512192 1274295696 13.461 93 11 22 0 1292765440 16092144 2377600
18:44:36 128126 8192000000 10258656112 10697056256 1307928624 13.767 96 39 78 0 1325535104 8705248 8901232
18:47:46 45207 8192000000 10267852640 10707365888 1311465120 13.799 96 25 50 0 1332888512 15588784 5834608
18:49:33 89078 8192000000 10272938400 10719571968 1341670240 14.072 98 19 38 0 1358693232 12518160 4504832
18:52:02 48289 8192000000 10265352192 10720198656 1348336480 14.133 98 13 29 0 1363319824 11510272 3473072
18:53:51 67847 8192000000 10279341456 10734600192 1377197904 14.391 100 23 46 0 1397593280 14419072 5976304
18:56:18 58005 8192000000 10282763808 10760364032 1379326752 14.411 100 12 24 0 1398326496 16748464 2251280
18:58:48 58751 8192000000 10281160208 10727723008 1363046928 14.265 99 25 50 0 1377803904 8765696 5991280
19:00:47 14378 8192000000 10294268768 10736209920 1383082368 14.444 100 44 86 1 1402098192 9423440 9592384
19:02:03 88173 8192000000 10285319584 10742018048 1357271488 14.213 98 12 24 0 1373107152 12500736 3334928
19:04:40 52989 8192000000 10284202960 10734424064 1365050816 14.283 99 17 34 0 1383120672 14490256 3579600
19:06:48 95278 8192000000 10296572800 10766606336 1337595536 14.036 96 5 10 0 1358082048 19472688 1013824
19:09:28 73629 8192000000 10299569232 10751000576 1393691552 14.539 100 37 74 0 1408506800 6036432 8778816
19:11:42 45278 8192000000 10292119232 10756071424 1377876064 14.398 98 17 32 1 1391479328 10090592 3512672
19:13:27 63977 8192000000 10313189616 10782568448 1388958368 14.497 99 11 22 0 1404124528 12509200 2656960
19:16:18 49355 8192000000 10302657952 10771423232 1387901936 14.487 99 12 24 0 1402857984 12298672 2657376
19:18:08 43845 8192000000 10279711616 10739986432 1338126304 14.041 96 21 42 0 1352889760 9721968 5041488
19:19:57 45114 8192000000 10295061344 10749681664 1342552896 14.080 96 15 30 0 1356235008 10420016 3262096
19:21:43 35791 8192000000 10278524448 10737418240 1353939824 14.183 98 14 28 0 1367030128 9876384 3213920
19:23:19 20411 8192000000 10295392864 10743341056 1377646096 14.395 100 55 111 0 1403181360 12762288 12772976
19:24:42 74208 8192000000 10288240976 10743783424 1352918912 14.174 98 19 38 0 1369161264 11653712 4588640
19:27:04 30369 8192000000 10298606384 10745712640 1358956016 14.228 98 20 40 1 1375819312 12469536 4393760
19:28:41 113637 8192000000 10281649136 10732601344 1328293344 13.952 96 17 34 0 1340557584 8103984 4160256
19:31:37 36591 8192000000 10262638128 10726256640 1350378416 14.151 98 13 26 1 1365340896 12118128 2844352
19:33:17 69468 8192000000 10255880064 10714021888 1333789760 14.001 96 11 22 0 1344225456 7969712 2465984
19:35:27 91583 8192000000 10267770368 10725634048 1337927696 14.039 97 16 32 1 1355458656 13702512 3828448
19:37:59 34015 8192000000 9257382688 9536897024 415176496 4.823 30 12 24 0 423638960 6051568 2410896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-07-2022 19:40:04 to 17-07-2022 19:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 174 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 17-07-2022 19:40:20 to 17-07-2022 19:40:22.

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          253026
MON$OLDEST_ACTIVE               253027
MON$OLDEST_SNAPSHOT             253027
MON$NEXT_TRANSACTION            253027
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                       2033312
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

17.07.22 19:40:23. 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-07-17 19:40:25 No severe PSQL-related problems occured
17.07.22 19:40:26. End of report.