Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2021 19:38
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 16367200 kB
MemAvailable 31331968 kB
Buffers 0 kB
Cached 15273124 kB
SwapCached 440 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 2021-12-29 19:37:58
DTS_END                         2021-12-29 19:37:58.8720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59470
TRN_ID                          239909
ATT_ID                          695
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: 9525.28 20037 1143034
customer order: creation 173.14 338 20777
customer order: refuse 14.41 332 1729
order to supplier: creation 45.70 1240 5484
order to supplier: removal 3.52 105465 422
invoice (draft): creation 46.62 3271 5594
invoice (draft): removal 54.98 787 6598
invoice accept: apply 42.10 18512 5052
invoice accept: cancel 49.81 802 5977
customer reserve: creation 3914.36 182 469723
customer reserve: removal 4880.09 60 585611
realization accept: apply 83.24 172 9989
realization accept: cancel 86.54 114 10385
payment from customer: creation 59.97 112 7196
payment from customer: removal 6.07 76 728
payment to supplier: creation 48.82 116 5858
payment to supplier: removal 3.46 51 415
service: total inventory turnovers 4.82 1014 578
service: total monetary turnovers 6.81 29 817
service: aggregate perf. data 0.81 19769 97
service: refresh index statistics 0.03 248291 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-12-2021 19:38:51 to 29-12-2021 19:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1095
TEST_TIME 2 3654
TEST_TIME 3 4062
TEST_TIME 4 4596
TEST_TIME 5 4994
TEST_TIME 6 4725
TEST_TIME 7 4533
TEST_TIME 8 4589
TEST_TIME 9 4718
TEST_TIME 10 4936
TEST_TIME 11 5086
TEST_TIME 12 5525
TEST_TIME 13 5800
TEST_TIME 14 5725
TEST_TIME 15 5820
TEST_TIME 16 6205
TEST_TIME 17 6258
TEST_TIME 18 6669
TEST_TIME 19 6914
TEST_TIME 20 7113
TEST_TIME 21 7535
TEST_TIME 22 7805
TEST_TIME 23 8267
TEST_TIME 24 8472
TEST_TIME 25 9041
TEST_TIME 26 9485
TEST_TIME 27 9587
TEST_TIME 28 10233
TEST_TIME 29 10244
TEST_TIME 30 10945
TEST_TIME 31 11029
TEST_TIME 32 10796
TEST_TIME 33 10689
TEST_TIME 34 10714
TEST_TIME 35 10697
TEST_TIME 36 10556
TEST_TIME 37 10478
TEST_TIME 38 10295
TEST_TIME 39 10556
TEST_TIME 40 10444
TEST_TIME 41 10458
TEST_TIME 42 10417
TEST_TIME 43 10441
TEST_TIME 44 10306
TEST_TIME 45 10162
TEST_TIME 46 10152
TEST_TIME 47 10005
TEST_TIME 48 9937
TEST_TIME 49 9924
TEST_TIME 50 9970
TEST_TIME 51 9946
TEST_TIME 52 9948
TEST_TIME 53 10019
TEST_TIME 54 9944
TEST_TIME 55 9935
TEST_TIME 56 9857
TEST_TIME 57 9775
TEST_TIME 58 9725
TEST_TIME 59 9724
TEST_TIME 60 9679
TEST_TIME 61 9616
TEST_TIME 62 9609
TEST_TIME 63 9617
TEST_TIME 64 9703
TEST_TIME 65 9712
TEST_TIME 66 9754
TEST_TIME 67 9751
TEST_TIME 68 9781
TEST_TIME 69 9815
TEST_TIME 70 9799
TEST_TIME 71 9933
TEST_TIME 72 9880
TEST_TIME 73 9936
TEST_TIME 74 9907
TEST_TIME 75 9871
TEST_TIME 76 9915
TEST_TIME 77 9862
TEST_TIME 78 9809
TEST_TIME 79 9734
TEST_TIME 80 9739
TEST_TIME 81 9692
TEST_TIME 82 9674
TEST_TIME 83 9665
TEST_TIME 84 9620
TEST_TIME 85 9597
TEST_TIME 86 9538
TEST_TIME 87 9504
TEST_TIME 88 9477
TEST_TIME 89 9481
TEST_TIME 90 9453
TEST_TIME 91 9485
TEST_TIME 92 9507
TEST_TIME 93 9509
TEST_TIME 94 9543
TEST_TIME 95 9586
TEST_TIME 96 9639
TEST_TIME 97 9585
TEST_TIME 98 9609
TEST_TIME 99 9678
TEST_TIME 100 9746
TEST_TIME 101 9737
TEST_TIME 102 9732
TEST_TIME 103 9693
TEST_TIME 104 9617
TEST_TIME 105 9633
TEST_TIME 106 9592
TEST_TIME 107 9590
TEST_TIME 108 9569
TEST_TIME 109 9534
TEST_TIME 110 9473
TEST_TIME 111 9462
TEST_TIME 112 9515
TEST_TIME 113 9535
TEST_TIME 114 9530
TEST_TIME 115 9553
TEST_TIME 116 9553
TEST_TIME 117 9536
TEST_TIME 118 9542
TEST_TIME 119 9550
TEST_TIME 120 9527

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2021 19:38:55 to 29-12-2021 19:38:58.

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 1577792 1577792 0 0.00 0 28889 6 0 0
doc_list_biud 1577792 1577792 0 0.00 0 70104 28 0 0
sp_add_invoice_to_stock 5163 5052 111 2.14 22 184237 18513 0 111
sp_cancel_adding_invoice 5977 5977 0 0.00 5 95715 803 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 12307 333 0 0
sp_cancel_customer_reserve 585611 585611 0 0.00 1 28430 60 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 3 5006 76 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 1644 52 0 0
sp_cancel_supplier_invoice 6604 6598 6 0.09 2 70111 787 0 6
sp_cancel_supplier_order 422 422 0 0.00 7 1048725 105466 0 0
sp_cancel_write_off 10385 10385 0 0.00 1 18169 115 0 0
sp_client_order 20777 20777 0 0.00 3 18712 338 0 0
sp_customer_reserve 469723 469723 0 0.00 0 48822 183 0 0
sp_fill_shopping_cart 31850 31850 0 0.00 1 12937 32 0 0
sp_fill_shopping_cart_clo_res 469718 469718 0 0.00 0 13850 13 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 24733 275 0 0
sp_kill_cost_storno 17505 17505 0 0.00 0 16249 59 0 0
sp_kill_qstorno_ret_qs2qd 1188627 1188627 0 0.00 0 70100 32 0 0
sp_kill_qty_storno 625758 625758 0 0.00 0 70102 70 0 0
sp_lock_selected_doc 20894 20894 0 0.00 0 9005 7 0 0
sp_make_cost_storno 28090 28090 0 0.00 0 26014 57 0 0
sp_multiply_rows_for_pdistr 28090 28090 0 0.00 0 8139 27 0 0
sp_multiply_rows_for_qdistr 20777 20777 0 0.00 0 12411 162 0 0
sp_pay_from_customer 7196 7196 0 0.00 4 7709 112 0 0
sp_pay_to_supplier 5858 5858 0 0.00 5 18096 116 0 0
sp_payment_common 13054 13054 0 0.00 1 6925 25 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 12300 292 0 0
sp_qd_handle_on_invoice_upd_sts 11024 11024 0 0.00 0 29859 325 0 0
sp_qd_handle_on_reserve_upd_sts 9989 9989 0 0.00 0 13222 55 0 0
sp_reserve_write_off 9989 9989 0 0.00 6 13521 172 0 0
sp_supplier_invoice 5594 5594 0 0.00 5 101216 3272 0 0
sp_supplier_order 5484 5484 0 0.00 4 51192 1240 0 0
srv_aggregate_perf_data 98 97 1 1.02 2948 187119 19770 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1835 133458 44678 0 0
srv_find_qd_qs_mism 1094680 1094680 0 0.00 0 16995 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 47 67193 1014 0 0
srv_make_money_saldo 817 817 0 0.00 0 3496 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 171748 325140 248291 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 5052 5052 0 0.00 0 13676 186 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 2974 64 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 4213 16 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 5698 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 5944 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 389 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 294 9 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 29683 1821 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 5635 12 0 0
t$perf-norm:sp_client_order 20777 20777 0 0.00 0 8427 19 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 2725 8 0 0
t$perf-norm:sp_pay_from_customer 7196 7196 0 0.00 0 13225 11 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 7006 15 0 0
t$perf-norm:sp_reserve_write_off 9989 9989 0 0.00 0 7724 13 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 14040 23 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 5981 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 5625 69 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 728 12 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 2956 28 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 1489 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10316 10094 222 2.15 0 6556 21 0 222
v_all_customers 38294 38294 0 0.00 0 14 0 0 0
v_all_suppliers 30648 30648 0 0.00 0 16 1 0 0
v_all_wares 41554 41554 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 3802 13 0 0
v_cancel_client_order 3458 3458 0 0.00 0 2717 5 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 2560 8 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2432 7 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1638 6 0 0
v_cancel_supplier_invoice 796 784 12 1.50 0 633 13 0 12
v_cancel_supplier_order 844 844 0 0.00 0 2568 15 0 0
v_cancel_write_off 2120 2120 0 0.00 0 3938 16 0 0
v_max_id_clo_ord 5483 5483 0 0.00 0 1209 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 6939 20 0 0
v_max_id_ord_sup 5590 5590 0 0.00 0 2851 4 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 416 2 0 0
v_max_non_paid_realizn 3620 3620 0 0.00 0 3189 5 0 0
v_min_id_clo_ord 5483 5483 0 0.00 0 1818 5 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2014 8 0 0
v_min_id_ord_sup 5590 5590 0 0.00 0 2771 4 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 3360 17 0 0
v_min_non_paid_realizn 7196 7196 0 0.00 0 6921 17 0 0
v_our_firm 10412 10412 0 0.00 0 12 1 0 0
v_reserve_write_off 19978 19978 0 0.00 0 3924 7 0 0
x_lock_depdocs_on_canc_invoice 12183 12183 0 0.00 0 22069 113 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 10486 395 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 1 51181 1167 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 2 101168 3162 0 0
x_make_qstorno_2100_3300 469723 469723 0 0.00 0 48821 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2021 19:38:59 to 29-12-2021 19:39:03.

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:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:03 27544 8192000000 9901140544 10212241408 982544448 10.709 100 88 176 0 1011597072 6278256 22774368
17:10:39 16081 8192000000 9951990208 10271997952 1028684576 11.156 100 90 180 0 1059866192 8743312 22438304
17:11:59 21364 8192000000 9983013952 10316230656 1064353216 11.498 100 79 158 0 1098074976 14459344 19262416
17:13:29 8357 8192000000 10037939056 10388160512 1127973728 12.102 100 73 146 0 1156465360 11174960 17316672
17:14:41 9881 8192000000 10084825376 10445574144 1170275568 12.499 100 77 153 0 1199677696 11907792 17494336
17:15:55 7010 8192000000 10132138976 10512216064 1229643408 13.051 100 76 151 0 1257834736 10113584 18077744
17:17:05 3816 8192000000 10175317328 10553606144 1253104816 13.267 100 82 164 1 1284528816 13837600 17586400
17:18:10 1204 8192000000 10197628672 10580586496 1286895504 13.576 100 98 195 1 1324285648 15782304 21607840
17:19:13 3255 8192000000 10225726800 10615586816 1305309248 13.743 100 85 170 0 1335033616 10176864 19547504
17:20:16 5630 8192000000 10207941616 10612240384 1312114240 13.805 100 32 60 2 1325637184 6461088 7061856
17:21:25 8220 8192000000 10226732208 10636169216 1324835280 13.920 100 54 108 1 1343351648 4742928 13773440
17:22:34 14291 8192000000 10230485184 10642206720 1324742656 13.920 100 39 78 0 1348873424 15513072 8617696
17:23:49 6973 8192000000 10222556432 10645315584 1331285072 13.979 100 27 54 1 1348008000 10325376 6397552
17:24:57 13641 8192000000 10233964064 10656538624 1337762672 14.037 100 17 34 0 1351085328 9774304 3548352
17:26:11 47142 8192000000 10220257856 10643181568 1317747408 13.856 99 40 80 0 1332817840 5609776 9460656
17:27:58 9430 8192000000 10201376480 10622095360 1315054624 13.832 100 28 56 0 1328163216 6067232 7041360
17:29:08 7373 8192000000 10181766320 10588499968 1284544992 13.554 100 37 74 0 1299448592 6478784 8424816
17:30:23 66558 8192000000 10138007504 10535944192 1227051232 13.027 97 19 38 0 1236378304 4669888 4657184
17:32:29 8416 8192000000 10159930992 10552500224 1249152720 13.230 99 25 50 1 1260983152 6371344 5459088
17:33:41 11058 8192000000 10169717600 10576117760 1273690800 13.455 100 24 48 1 1285032592 5547744 5794048
17:34:53 11321 8192000000 10178448896 10594889728 1293738976 13.638 100 10 20 0 1301485840 5217744 2529120
17:36:04 28187 8192000000 10183326240 10597400576 1293765936 13.639 100 26 52 0 1305756544 6317680 5672928
17:37:34 8422 8192000000 10201710176 10611941376 1302106880 13.714 100 16 32 0 1310700336 4947200 3646256
17:38:55 9941 8192000000 10239135904 10643636224 1327780096 13.947 100 59 117 1 1354217600 13176512 13260992
17:40:10 25931 8192000000 10274535152 10682884096 1356616336 14.207 100 62 124 4 1387445872 17282384 13547152
17:41:42 23106 8192000000 10270701600 10693509120 1360579488 14.243 100 51 102 0 1391164416 19073632 11511296
17:43:14 84571 8192000000 10288699120 10716651520 1372110320 14.346 100 26 52 1 1399412992 21262288 6040384
17:45:43 8757 8192000000 10310569136 10737360896 1389009648 14.497 100 37 74 0 1425457040 26190112 10257280
17:47:00 16747 8192000000 10335878848 10756771840 1397235968 14.570 100 65 129 0 1443008592 30068944 15703680
17:48:25 4558 8192000000 10340570736 10762805248 1398776592 14.584 100 65 130 2 1451297664 35860960 16660112
17:49:32 13155 8192000000 10344096432 10760097792 1389024880 14.497 99 64 128 2 1445322656 39852160 16445616
17:50:57 3647 8192000000 10362148768 10775908352 1411318768 14.696 100 84 167 0 1468329136 36491776 20518592
17:52:06 1834 8192000000 10374490064 10786689024 1414836080 14.727 100 87 174 2 1478962160 42922096 21203984
17:53:15 3282 8192000000 10356236896 10765045760 1382699136 14.441 99 75 148 1 1447366976 46006400 18661440
17:54:25 6936 8192000000 10352830080 10763968512 1387359648 14.482 100 72 141 0 1452983248 47770288 17853312
17:55:40 3105 8192000000 10349129456 10753581056 1376061952 14.381 100 86 170 3 1449453792 51197312 22194528
17:56:45 6706 8192000000 10370195344 10783911936 1397365312 14.572 99 72 140 1 1463411056 47513920 18531824
17:57:57 6888 8192000000 10360400672 10769731584 1375578960 14.377 99 81 162 0 1450752112 53844592 21328560
17:59:09 11734 8192000000 10352320592 10784256000 1381143024 14.427 99 46 91 0 1445182880 51802160 12237696
18:00:26 5298 8192000000 10365366560 10792554496 1387152848 14.480 99 47 94 1 1466288528 65889232 13246448
18:01:37 6753 8192000000 10359373424 10787856384 1397762672 14.575 100 44 89 1 1470824256 60938064 12123520
18:02:45 18361 8192000000 10342425392 10781642752 1383291392 14.446 99 28 56 1 1442956368 51819792 7845184
18:04:06 29902 8192000000 10333898352 10786750464 1380605152 14.422 99 25 50 0 1437639168 50346656 6687360
18:05:37 22920 8192000000 10308143744 10758713344 1358613856 14.225 99 20 40 2 1407876224 43480064 5782304
18:07:25 55021 8192000000 10285287040 10733580288 1342091408 14.076 98 13 26 0 1361954176 16995056 2867712
18:09:30 73179 8192000000 10272534832 10708942848 1322205072 13.897 96 26 52 0 1335917520 8005120 5707328
18:11:44 127974 8192000000 10288340768 10739298304 1339716768 14.055 97 6 12 0 1354135312 13115744 1302800
18:14:52 27859 8192000000 10297698640 10738135040 1361368080 14.250 98 17 34 0 1372825136 8011696 3445360
18:16:21 75028 8192000000 10293705952 10753826816 1378627072 14.404 100 10 20 0 1396692352 15938400 2126880
18:18:36 87256 8192000000 10308389856 10757828608 1384320112 14.455 99 9 18 1 1396074960 9868032 1886816
18:21:06 63468 8192000000 10293471696 10743070720 1382625936 14.440 99 22 44 1 1397864096 10058400 5179760
18:23:10 60160 8192000000 10307172512 10768064512 1396448320 14.563 100 27 52 4 1415786688 12498704 6839664
18:25:12 43994 8192000000 10311526112 10768367616 1402292048 14.615 100 24 48 0 1419496848 11774464 5430336
18:26:57 55232 8192000000 10290590144 10743242752 1351412800 14.160 96 19 38 0 1369221600 13361312 4447488
18:28:56 33438 8192000000 10316499952 10771800064 1394945744 14.550 100 53 104 4 1425947328 18841056 12160528
18:30:34 19421 8192000000 10280699152 10752204800 1374158400 14.364 100 7 13 1 1397773136 21754448 1860288
18:31:59 51668 8192000000 10270309856 10729701376 1341580576 14.072 97 13 26 1 1351586736 7278720 2727440
18:33:51 51988 8192000000 10263840608 10729566208 1364701936 14.280 99 11 23 0 1378891552 11239264 2950352
18:36:09 77387 8192000000 10276769280 10737287168 1366923776 14.299 99 16 32 0 1382500608 11557712 4019120
18:38:33 81018 8192000000 10271408704 10730553344 1323919200 13.912 96 10 20 0 1338558080 12357504 2281376
18:40:54 46742 8192000000 10255423456 10703130624 1305850544 13.748 95 17 33 2 1324103536 14135376 4117616
18:42:43 40698 8192000000 10278847216 10731716608 1350079504 14.148 98 21 42 0 1365332864 10507264 4746096
18:44:29 57448 8192000000 10295680064 10731433984 1344288272 14.096 98 27 54 0 1363235280 12911888 6035120
18:46:26 68178 8192000000 10285720672 10726801408 1333234336 13.996 96 19 38 0 1352944896 15218016 4492544
18:48:36 43872 8192000000 10280304272 10720768000 1329559984 13.963 97 15 30 0 1341812864 9285296 2967584
18:50:20 34885 8192000000 10277175760 10728284160 1329670480 13.964 97 43 86 2 1353535728 14813552 9051696
18:52:01 14910 8192000000 10278172880 10712526848 1336975296 14.030 98 35 70 0 1353461840 9151088 7335456
18:53:18 32810 8192000000 10293787904 10735247360 1355347440 14.196 99 45 90 3 1381406048 16159232 9899376
18:54:53 70529 8192000000 10276620656 10727804928 1369295440 14.321 100 29 58 0 1383765392 7849408 6620544
18:57:16 20565 8192000000 10288519488 10726637568 1367661008 14.306 100 56 112 1 1392453120 12687104 12105008
18:58:47 76240 8192000000 10258140944 10730643456 1333409712 13.998 97 9 18 0 1348683232 12907808 2365712
19:01:03 101694 8192000000 10275519904 10738294784 1352170528 14.167 98 10 19 0 1365983616 11949088 1864000
19:03:56 56557 8192000000 10276097216 10730893312 1372027568 14.345 100 24 48 0 1384687072 7728592 4930912
19:05:53 51682 8192000000 10282297936 10745729024 1355831584 14.200 98 16 32 0 1369815248 9856832 4126832
19:08:30 69999 8192000000 10282259680 10748219392 1345489744 14.107 97 23 46 1 1367215584 15893760 5832080
19:10:48 54932 8192000000 10312579520 10773569536 1373249600 14.356 98 26 52 0 1399019152 20059968 5709584
19:12:45 54335 8192000000 10288841696 10750586880 1340935792 14.066 96 14 28 0 1357184176 13227264 3021120
19:14:40 32581 8192000000 10307322880 10755497984 1372912704 14.353 99 41 82 0 1397709312 15178816 9617792
19:16:20 98442 8192000000 10294394976 10767929344 1368998736 14.318 99 6 12 0 1401411568 30749904 1662928
19:18:59 53806 8192000000 10300298128 10750005248 1375561808 14.377 99 16 32 1 1393127360 14532560 3032992
19:21:00 133458 8192000000 10287875248 10736922624 1357613168 14.216 97 33 66 3 1379078368 13234896 8230304
19:24:19 47861 8192000000 10253880496 10716225536 1317827904 13.857 95 11 22 1 1329018672 8782400 2408368
19:26:07 28682 8192000000 10253571472 10718023680 1357661168 14.216 100 4 9 0 1367597328 9310736 625424
19:27:38 82036 8192000000 10289830464 10754531328 1366928640 14.300 99 19 38 0 1388532368 16784832 4818896
19:30:07 67194 8192000000 10281806608 10737688576 1349581504 14.144 98 12 24 0 1366766144 14739440 2445200
19:32:14 46379 8192000000 10276513104 10733731840 1344848336 14.101 98 14 28 0 1356648608 8493520 3306752
19:34:11 67582 8192000000 10286690976 10737618944 1339316128 14.051 97 25 49 2 1353553776 8997808 5239840
19:36:19 51753 8192000000 10267945696 10717728768 1321866208 13.894 97 26 52 1 1338282608 11123856 5292544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2021 19:39:05 to 29-12-2021 19:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 111 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2021 19:39:21 to 29-12-2021 19:39:23.

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          237666
MON$OLDEST_ACTIVE               240535
MON$OLDEST_SNAPSHOT             240535
MON$NEXT_TRANSACTION            240535
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                       2051232
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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Wed Dec 29 19:38:08 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Wed Dec 29 19:38:09 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
--- end of diff output ---

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

29.12.21 19:39:24. 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-12-29 19:39:26 No severe PSQL-related problems occured
29.12.21 19:39:28. End of report.