Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2022 16: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 16591356 kB
MemAvailable 31356356 kB
Buffers 0 kB
Cached 15110540 kB
SwapCached 1196 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.9
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-01-02 16:38:01
DTS_END                         2022-01-02 16:38:01.6470
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/35160
TRN_ID                          237083
ATT_ID                          630
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: 9095.60 23336 1091472
customer order: creation 166.13 362 19935
customer order: refuse 15.03 327 1804
order to supplier: creation 45.44 1233 5453
order to supplier: removal 3.34 97996 401
invoice (draft): creation 45.64 3422 5477
invoice (draft): removal 52.74 766 6329
invoice accept: apply 40.38 18488 4845
invoice accept: cancel 47.79 754 5735
customer reserve: creation 3705.34 183 444641
customer reserve: removal 4677.75 54 561330
realization accept: apply 83.13 165 9975
realization accept: cancel 85.88 113 10305
payment from customer: creation 57.61 93 6913
payment from customer: removal 5.38 81 645
payment to supplier: creation 47.74 113 5729
payment to supplier: removal 3.57 105 428
service: total inventory turnovers 4.97 1048 597
service: total monetary turnovers 6.84 50 821
service: aggregate perf. data 0.88 19921 105
service: refresh index statistics 0.03 321453 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3837
TEST_TIME 2 4037
TEST_TIME 3 4471
TEST_TIME 4 5515
TEST_TIME 5 5741
TEST_TIME 6 5679
TEST_TIME 7 5784
TEST_TIME 8 5723
TEST_TIME 9 5889
TEST_TIME 10 5887
TEST_TIME 11 6141
TEST_TIME 12 6229
TEST_TIME 13 6364
TEST_TIME 14 6888
TEST_TIME 15 6899
TEST_TIME 16 6922
TEST_TIME 17 7149
TEST_TIME 18 7500
TEST_TIME 19 8019
TEST_TIME 20 8531
TEST_TIME 21 8963
TEST_TIME 22 9391
TEST_TIME 23 9875
TEST_TIME 24 10438
TEST_TIME 25 10738
TEST_TIME 26 10890
TEST_TIME 27 10978
TEST_TIME 28 10879
TEST_TIME 29 10667
TEST_TIME 30 10396
TEST_TIME 31 10516
TEST_TIME 32 10611
TEST_TIME 33 10514
TEST_TIME 34 10354
TEST_TIME 35 10209
TEST_TIME 36 10057
TEST_TIME 37 10067
TEST_TIME 38 10152
TEST_TIME 39 10236
TEST_TIME 40 10224
TEST_TIME 41 10086
TEST_TIME 42 10080
TEST_TIME 43 9936
TEST_TIME 44 9950
TEST_TIME 45 9925
TEST_TIME 46 9801
TEST_TIME 47 9827
TEST_TIME 48 9839
TEST_TIME 49 9682
TEST_TIME 50 9588
TEST_TIME 51 9459
TEST_TIME 52 9454
TEST_TIME 53 9398
TEST_TIME 54 9557
TEST_TIME 55 9504
TEST_TIME 56 9600
TEST_TIME 57 9636
TEST_TIME 58 9554
TEST_TIME 59 9546
TEST_TIME 60 9800
TEST_TIME 61 9754
TEST_TIME 62 9879
TEST_TIME 63 9908
TEST_TIME 64 9963
TEST_TIME 65 9918
TEST_TIME 66 9830
TEST_TIME 67 9761
TEST_TIME 68 9742
TEST_TIME 69 9711
TEST_TIME 70 9664
TEST_TIME 71 9590
TEST_TIME 72 9548
TEST_TIME 73 9499
TEST_TIME 74 9495
TEST_TIME 75 9544
TEST_TIME 76 9521
TEST_TIME 77 9549
TEST_TIME 78 9542
TEST_TIME 79 9553
TEST_TIME 80 9533
TEST_TIME 81 9577
TEST_TIME 82 9613
TEST_TIME 83 9591
TEST_TIME 84 9533
TEST_TIME 85 9616
TEST_TIME 86 9578
TEST_TIME 87 9550
TEST_TIME 88 9471
TEST_TIME 89 9432
TEST_TIME 90 9382
TEST_TIME 91 9361
TEST_TIME 92 9378
TEST_TIME 93 9323
TEST_TIME 94 9285
TEST_TIME 95 9298
TEST_TIME 96 9300
TEST_TIME 97 9319
TEST_TIME 98 9343
TEST_TIME 99 9316
TEST_TIME 100 9292
TEST_TIME 101 9335
TEST_TIME 102 9334
TEST_TIME 103 9361
TEST_TIME 104 9420
TEST_TIME 105 9390
TEST_TIME 106 9436
TEST_TIME 107 9450
TEST_TIME 108 9426
TEST_TIME 109 9432
TEST_TIME 110 9399
TEST_TIME 111 9334
TEST_TIME 112 9289
TEST_TIME 113 9262
TEST_TIME 114 9260
TEST_TIME 115 9231
TEST_TIME 116 9235
TEST_TIME 117 9222
TEST_TIME 118 9173
TEST_TIME 119 9172
TEST_TIME 120 9096

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2022 16:39:53 to 02-01-2022 16: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 1503942 1503942 0 0.00 0 19335 6 0 0
doc_list_biud 1503942 1503942 0 0.00 0 23802 26 0 0
sp_add_invoice_to_stock 4951 4845 106 2.14 16 180276 18488 0 106
sp_cancel_adding_invoice 5735 5735 0 0.00 5 61770 754 0 0
sp_cancel_client_order 1804 1804 0 0.00 4 9958 327 0 0
sp_cancel_customer_reserve 561330 561330 0 0.00 1 21727 54 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 6454 81 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 2 4627 105 0 0
sp_cancel_supplier_invoice 6343 6329 14 0.22 2 14797 767 0 14
sp_cancel_supplier_order 401 401 0 0.00 8 1077867 97997 0 0
sp_cancel_write_off 10305 10305 0 0.00 1 15409 114 0 0
sp_client_order 19935 19935 0 0.00 3 20971 363 0 0
sp_customer_reserve 444641 444641 0 0.00 0 38285 184 0 0
sp_fill_shopping_cart 30855 30855 0 0.00 1 16679 41 0 0
sp_fill_shopping_cart_clo_res 444639 444639 0 0.00 0 20235 15 0 0
sp_get_clo_for_invoice 4843 4843 0 0.00 0 33494 313 0 0
sp_kill_cost_storno 17113 17113 0 0.00 0 13549 55 0 0
sp_kill_qstorno_ret_qs2qd 1139695 1139695 0 0.00 0 21718 30 0 0
sp_kill_qty_storno 600721 600721 0 0.00 0 23801 64 0 0
sp_lock_selected_doc 20580 20580 0 0.00 0 9385 7 0 0
sp_make_cost_storno 27459 27459 0 0.00 0 9657 47 0 0
sp_multiply_rows_for_pdistr 27459 27459 0 0.00 0 7920 28 0 0
sp_multiply_rows_for_qdistr 19935 19935 0 0.00 0 20671 165 0 0
sp_pay_from_customer 6913 6913 0 0.00 4 9982 93 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 10978 114 0 0
sp_payment_common 12642 12642 0 0.00 1 7519 23 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 9948 292 0 0
sp_qd_handle_on_invoice_upd_sts 10577 10577 0 0.00 0 23800 316 0 0
sp_qd_handle_on_reserve_upd_sts 9975 9975 0 0.00 0 19181 56 0 0
sp_reserve_write_off 9975 9975 0 0.00 6 19219 165 0 0
sp_supplier_invoice 5477 5477 0 0.00 8 106453 3423 0 0
sp_supplier_order 5453 5453 0 0.00 5 46687 1234 0 0
srv_aggregate_perf_data 106 105 1 0.94 2621 418720 19921 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2264 121775 48576 0 0
srv_find_qd_qs_mism 1045478 1045478 0 0.00 0 19334 5 0 0
srv_make_invnt_saldo 597 597 0 0.00 33 62007 1048 0 0
srv_make_money_saldo 821 821 0 0.00 1 7722 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 197130 431564 321453 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 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 4845 4845 0 0.00 0 21201 195 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 6337 89 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 1433 10 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 6632 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 154 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 535 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 875 13 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 17347 1489 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 6911 17 0 0
t$perf-norm:sp_client_order 19935 19935 0 0.00 0 7499 17 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 710 4 0 0
t$perf-norm:sp_pay_from_customer 6913 6913 0 0.00 0 4860 13 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 6574 9 0 0
t$perf-norm:sp_reserve_write_off 9975 9975 0 0.00 0 6782 10 0 0
t$perf-norm:sp_supplier_invoice 5477 5477 0 0.00 0 4391 20 0 0
t$perf-norm:sp_supplier_order 5453 5453 0 0.00 0 3913 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 282 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 54 1 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 1278 16 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 1798 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9896 9684 212 2.14 0 11739 20 0 212
v_all_customers 36378 36378 0 0.00 0 26 1 0 0
v_all_suppliers 30134 30134 0 0.00 0 22 1 0 0
v_all_wares 39868 39868 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2153 9 0 0
v_cancel_client_order 3608 3608 0 0.00 0 4629 5 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 6436 13 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 5621 9 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4425 15 0 0
v_cancel_supplier_invoice 852 824 28 3.28 0 2535 18 0 28
v_cancel_supplier_order 802 802 0 0.00 0 1283 5 0 0
v_cancel_write_off 1940 1940 0 0.00 0 8561 16 0 0
v_max_id_clo_ord 5453 5453 0 0.00 0 1872 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 2834 19 0 0
v_max_id_ord_sup 5467 5467 0 0.00 0 7783 6 0 0
v_max_non_paid_invoice 1582 1582 0 0.00 0 2703 4 0 0
v_max_non_paid_realizn 3678 3678 0 0.00 0 3644 5 0 0
v_min_id_clo_ord 5453 5453 0 0.00 0 6725 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 2378 8 0 0
v_min_id_ord_sup 5467 5467 0 0.00 0 5289 10 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 5583 17 0 0
v_min_non_paid_realizn 6913 6913 0 0.00 0 4347 15 0 0
v_our_firm 9960 9960 0 0.00 0 21 1 0 0
v_reserve_write_off 19950 19950 0 0.00 0 6436 5 0 0
x_lock_depdocs_on_canc_invoice 11652 11652 0 0.00 0 17132 117 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 11962 364 0 0
x_make_qstorno_1000_1200 5453 5453 0 0.00 2 46635 1160 0 0
x_make_qstorno_1200_2000 5477 5477 0 0.00 3 101236 3270 0 0
x_make_qstorno_2100_3300 444641 444641 0 0.00 0 36483 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2022 16:39:58 to 02-01-2022 16: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
14:08:05 9 8192000000 8801866912 8813969408 7960752 0.097 2 2 4 0 8112416 66704 84960
14:09:06 34490 8192000000 9915850224 10232250368 998386304 10.863 100 91 182 0 1028875264 6522880 23966080
14:10:47 27655 8192000000 9967316240 10295398400 1048647824 11.348 100 88 176 0 1079888880 8963456 22277600
14:12:16 17677 8192000000 10001230432 10339049472 1078668768 11.635 100 82 164 0 1108850816 9662272 20519776
14:13:37 12934 8192000000 10045937088 10396106752 1127372000 12.097 100 77 152 0 1156504864 10539600 18593264
14:14:53 9782 8192000000 10083057248 10446770176 1173235664 12.527 100 82 163 2 1204619856 12446752 18937440
14:16:05 5422 8192000000 10141973152 10512125952 1226392192 13.021 100 87 174 0 1258926928 13356256 19178480
14:17:12 5807 8192000000 10162820368 10541867008 1250283632 13.241 100 81 162 0 1285843760 18050016 17510112
14:18:20 2096 8192000000 10207043440 10588618752 1294859200 13.648 100 96 192 0 1331671520 15852640 20959680
14:19:24 36966 8192000000 10226876512 10616102912 1309832576 13.785 100 60 120 3 1330498992 6411584 14254832
14:21:01 8952 8192000000 10199110672 10611867648 1314274560 13.825 100 29 57 0 1327359968 6746768 6338640
14:22:13 9893 8192000000 10202014544 10621083648 1321022192 13.886 100 16 32 1 1331406928 7050960 3333776
14:23:25 10110 8192000000 10209458752 10634563584 1331716720 13.983 100 21 41 2 1342217360 5488960 5011680
14:24:37 34456 8192000000 10252768704 10654789632 1335808720 14.020 100 46 92 1 1353661472 5931776 11920976
14:26:12 5575 8192000000 10232273888 10649591808 1336854864 14.029 100 23 46 2 1348286608 5763504 5668240
14:27:18 6724 8192000000 10233760192 10657910784 1343287568 14.087 100 15 30 0 1352302240 5930048 3084624
14:28:31 50430 8192000000 10209799728 10634113024 1295341248 13.653 97 16 32 1 1303767008 4915936 3509824
14:30:24 5040 8192000000 10154713120 10562310144 1236570384 13.115 97 24 48 0 1252140480 9967760 5602336
14:31:29 8802 8192000000 10142552464 10544754688 1215821424 12.923 96 20 39 1 1225589008 5151968 4615616
14:32:42 12003 8192000000 10152155840 10553655296 1259719936 13.327 100 24 48 0 1270457088 5257776 5479376
14:33:54 21478 8192000000 10165461888 10576846848 1276818752 13.484 100 21 42 0 1287447472 6000928 4627792
14:35:16 5639 8192000000 10194578912 10608099328 1297126368 13.669 100 17 34 3 1311241888 9788784 4326736
14:36:24 13695 8192000000 10200763696 10616664064 1308600816 13.773 100 26 51 1 1320791008 5585088 6605104
14:37:41 65605 8192000000 10224132176 10635816960 1321497856 13.890 100 33 64 0 1335159280 5177536 8483888
14:39:53 2263 8192000000 10263311744 10673963008 1351244512 14.159 100 49 98 0 1370975056 8540448 11190096
14:41:03 10519 8192000000 10278281328 10695012352 1366619360 14.297 100 48 96 0 1390543968 13555984 10368624
14:42:18 7267 8192000000 10307452400 10719584256 1380190720 14.418 100 60 120 1 1413359664 19814608 13354336
14:43:31 14802 8192000000 10315669696 10734178304 1385522576 14.466 100 62 123 0 1426191440 26674656 13994208
14:44:53 47440 8192000000 10303353456 10734350336 1382390160 14.438 99 45 90 3 1413764704 20590704 10783840
14:46:58 13441 8192000000 10333804480 10760691712 1406733600 14.655 100 30 60 1 1437813248 23132896 7946752
14:48:15 13144 8192000000 10357502112 10781093888 1423236720 14.801 100 55 110 1 1466328656 30276592 12815344
14:49:30 8955 8192000000 10366434720 10784317440 1410582992 14.689 99 66 132 1 1463886352 37363664 15939696
14:50:42 2338 8192000000 10376961408 10791895040 1425300064 14.820 100 83 166 1 1487538592 42455392 19783136
14:51:46 4672 8192000000 10400858624 10811449344 1430521216 14.866 100 92 184 2 1501481568 48175936 22784416
14:52:56 3874 8192000000 10365078320 10780958720 1410456176 14.688 100 65 128 0 1470860224 44319424 16084624
14:54:06 7439 8192000000 10390129312 10809438208 1423630976 14.805 100 72 142 2 1493957840 52281584 18045280
14:55:19 6081 8192000000 10381855472 10789359616 1408332656 14.669 100 83 166 1 1485517776 55527072 21658048
14:56:30 4419 8192000000 10356133072 10770288640 1386399376 14.474 100 68 136 1 1464427504 60575712 17452416
14:57:46 2493 8192000000 10356394048 10773352448 1394693312 14.548 100 56 112 0 1459614576 49699808 15221456
14:58:52 13632 8192000000 10353049648 10789040128 1403627056 14.627 100 38 76 1 1462986208 48463792 10895360
15:00:07 20800 8192000000 10314824016 10751696896 1371890352 14.344 99 23 46 0 1417424304 39063200 6470752
15:02:07 19587 8192000000 10290101936 10724323328 1342595024 14.081 99 23 46 0 1387289536 38172960 6521552
15:03:31 74407 8192000000 10257466944 10688561152 1295851632 13.658 96 9 18 0 1325428320 27168960 2407728
15:05:49 76348 8192000000 10233985936 10663952384 1290958960 13.613 96 26 50 3 1310055936 13487952 5609024
15:08:08 72704 8192000000 10265046320 10705215488 1349694000 14.145 100 33 66 0 1366332800 8447968 8190832
15:10:32 61018 8192000000 10289308176 10717679616 1346033328 14.112 98 32 63 1 1363107888 9948672 7125888
15:12:35 30727 8192000000 10283726832 10727112704 1383267248 14.446 100 20 40 1 1394888672 6947680 4673744
15:14:07 39961 8192000000 10301010464 10730213376 1349114544 14.140 97 28 58 3 1365942336 10109152 6718640
15:15:49 49426 8192000000 10290882864 10749698048 1374329344 14.366 99 15 30 0 1398068304 19657024 4081936
15:17:41 45418 8192000000 10287532576 10751692800 1386127792 14.471 100 21 38 2 1409793584 18852896 4812896
15:20:02 52901 8192000000 10291665728 10755989504 1399766816 14.593 100 14 28 1 1414753728 11608448 3378464
15:21:57 67152 8192000000 10304209872 10767785984 1405944320 14.648 100 21 42 0 1423068176 12113824 5010032
15:24:21 47468 8192000000 10314519408 10768101376 1412022688 14.702 100 32 64 0 1428560752 9083872 7454192
15:26:13 59095 8192000000 10300632624 10776621056 1413285904 14.713 100 5 10 0 1424800320 10451040 1063376
15:28:28 63212 8192000000 10304157776 10768351232 1401186128 14.606 99 22 43 0 1414711424 8935472 4589824
15:30:32 62883 8192000000 10324531712 10795880448 1403720384 14.628 99 12 24 1 1425828752 19414752 2693616
15:33:33 68720 8192000000 10292717232 10762625024 1375297440 14.374 98 17 33 1 1388148800 8842960 4008400
15:35:42 30077 8192000000 10308334576 10760060928 1388473664 14.492 99 30 60 1 1406374016 10957136 6943216
15:37:19 50631 8192000000 10314671168 10776649728 1411060864 14.693 100 24 48 1 1433063776 16601024 5401888
15:39:11 121775 8192000000 10292246064 10754228224 1244020224 13.183 88 12 24 1 1260985472 14472624 2492624
15:42:12 61253 8192000000 10270872672 10705866752 1277975408 13.495 93 16 32 1 1292904736 11584736 3344592
15:44:17 71348 8192000000 10288847360 10731515904 1375821376 14.379 100 56 112 0 1402626448 14290528 12514544
15:46:28 46754 8192000000 10262654160 10725384192 1353910528 14.183 98 11 22 1 1373979824 17357248 2712048
15:48:15 30867 8192000000 10261875104 10734428160 1357267936 14.213 99 7 14 1 1371019408 12209136 1542336
15:50:16 96955 8192000000 10240169712 10697101312 1289150928 13.596 94 14 28 1 1308761824 15996448 3614448
15:52:59 70226 8192000000 10259473376 10704728064 1333137744 13.995 98 16 32 0 1345959696 9678240 3143712
15:55:12 90093 8192000000 10267995344 10720727040 1317164400 13.851 96 18 36 0 1332613136 10883280 4565456
15:57:43 47152 8192000000 10277324160 10721460224 1339307808 14.051 98 23 46 1 1359420880 15185760 4927312
15:59:43 104243 8192000000 10252537376 10706956288 1284528832 13.554 94 15 30 1 1304566048 16783552 3253664
16:02:27 105352 8192000000 10270141296 10731016192 1326757600 13.938 97 21 40 1 1342711632 11502352 4451680
16:05:13 92018 8192000000 10277947312 10729197568 1348046656 14.130 98 28 56 2 1362426048 8346624 6032768
16:08:25 43980 8192000000 10288154192 10740756480 1376240720 14.383 99 19 38 0 1393268880 12872768 4155392
16:10:10 81603 8192000000 10300652848 10773135360 1391429008 14.519 100 8 16 0 1409252096 16249024 1574064
16:12:31 70815 8192000000 10310909424 10776080384 1400721552 14.601 100 13 26 0 1425913664 22565680 2626432
16:14:42 75442 8192000000 10309979376 10767187968 1392864832 14.531 99 9 18 0 1403457232 8416176 2176224
16:17:04 70008 8192000000 10310926480 10771636224 1393396320 14.536 99 11 22 0 1408034464 12168864 2469280
16:19:14 63770 8192000000 10319838144 10774343680 1388118448 14.489 98 17 34 1 1400501472 8148752 4234272
16:21:19 50081 8192000000 10319221552 10770952192 1366713488 14.298 96 17 34 0 1387127104 16692592 3721024
16:23:12 57169 8192000000 10309230016 10760503296 1387880032 14.487 100 13 26 0 1409535376 18768416 2886928
16:25:10 68045 8192000000 10306970432 10759946240 1354111104 14.184 97 21 42 0 1373464096 15008336 4344656
16:27:20 19824 8192000000 10302370672 10740678656 1368578976 14.314 99 40 79 1 1388961888 11865536 8517376
16:28:42 94712 8192000000 10270619648 10722619392 1338335200 14.042 97 20 40 0 1354016432 11135376 4545856
16:31:19 3212 8192000000 10275185344 10707263488 1355570848 14.198 99 51 102 0 1373999072 7401648 11026576
16:32:22 94486 8192000000 10262703680 10727546880 1356839456 14.209 99 11 22 1 1372579456 13306704 2433296
16:34:57 51976 8192000000 10254513216 10707730432 1330739456 13.974 97 12 25 0 1343000112 9617040 2643616
16:37:12 56153 8192000000 9749647744 10128969728 869033824 9.590 65 12 24 2 879713568 7890128 2789616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-01-2022 16:40:04 to 02-01-2022 16:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 106 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          237156
MON$OLDEST_ACTIVE               237365
MON$OLDEST_SNAPSHOT             237365
MON$NEXT_TRANSACTION            237365
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2022848
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:302: 
:303: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:304:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:305: 
:306: 
:307: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:308:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:309: 
:310: 
:311: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:312:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:313: 
:314: 
:315: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:316:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:317: 
:318: 
:319: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:320:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:321: 
:322: 
:323: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:324:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:325: 
:326: 
:327: mscs-test-161.budzdorov.ru    Sun Jan  2 16:38:25 2022
:328:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:329: 
--- end of diff output ---

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

02.01.22 16:40:22. 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-01-02 16:40:25 No severe PSQL-related problems occured
02.01.22 16:40:26. End of report.