Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.05.2022 16:42
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 16352536 kB
MemAvailable 31500636 kB
Buffers 0 kB
Cached 15331220 kB
SwapCached 3260 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-03 16:42:20
DTS_END                         2022-05-03 16:42:20.8840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60514
TRN_ID                          249798
ATT_ID                          719
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: 9631.53 18933 1155784
customer order: creation 187.15 354 22458
customer order: refuse 15.82 378 1898
order to supplier: creation 48.87 1363 5864
order to supplier: removal 3.68 98793 442
invoice (draft): creation 48.70 3325 5844
invoice (draft): removal 55.48 782 6657
invoice accept: apply 44.23 17698 5308
invoice accept: cancel 50.08 805 6010
customer reserve: creation 4033.75 178 484050
customer reserve: removal 4834.60 60 580152
realization accept: apply 89.41 178 10729
realization accept: cancel 79.36 105 9523
payment from customer: creation 64.02 130 7682
payment from customer: removal 6.50 80 780
payment to supplier: creation 53.49 141 6419
payment to supplier: removal 3.58 43 430
service: total inventory turnovers 4.83 997 579
service: total monetary turnovers 7.18 61 861
service: aggregate perf. data 0.78 24536 94
service: refresh index statistics 0.03 228651 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-05-2022 16:43:17 to 03-05-2022 16:43:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3838
TEST_TIME 2 5290
TEST_TIME 3 5317
TEST_TIME 4 5150
TEST_TIME 5 5552
TEST_TIME 6 5374
TEST_TIME 7 5613
TEST_TIME 8 5737
TEST_TIME 9 5752
TEST_TIME 10 6144
TEST_TIME 11 6371
TEST_TIME 12 6497
TEST_TIME 13 7020
TEST_TIME 14 6920
TEST_TIME 15 7179
TEST_TIME 16 7450
TEST_TIME 17 7583
TEST_TIME 18 7784
TEST_TIME 19 7722
TEST_TIME 20 8093
TEST_TIME 21 8456
TEST_TIME 22 8673
TEST_TIME 23 8783
TEST_TIME 24 8849
TEST_TIME 25 9027
TEST_TIME 26 9159
TEST_TIME 27 9212
TEST_TIME 28 9801
TEST_TIME 29 9812
TEST_TIME 30 10961
TEST_TIME 31 10823
TEST_TIME 32 10776
TEST_TIME 33 10808
TEST_TIME 34 10605
TEST_TIME 35 10648
TEST_TIME 36 10585
TEST_TIME 37 10590
TEST_TIME 38 10521
TEST_TIME 39 10488
TEST_TIME 40 10530
TEST_TIME 41 10372
TEST_TIME 42 10199
TEST_TIME 43 10236
TEST_TIME 44 10201
TEST_TIME 45 10217
TEST_TIME 46 10046
TEST_TIME 47 9973
TEST_TIME 48 9951
TEST_TIME 49 9920
TEST_TIME 50 10030
TEST_TIME 51 10029
TEST_TIME 52 10002
TEST_TIME 53 9929
TEST_TIME 54 9849
TEST_TIME 55 9808
TEST_TIME 56 9674
TEST_TIME 57 9696
TEST_TIME 58 9639
TEST_TIME 59 9541
TEST_TIME 60 9662
TEST_TIME 61 9643
TEST_TIME 62 9672
TEST_TIME 63 9761
TEST_TIME 64 9824
TEST_TIME 65 9830
TEST_TIME 66 9847
TEST_TIME 67 9862
TEST_TIME 68 9801
TEST_TIME 69 9990
TEST_TIME 70 9984
TEST_TIME 71 9938
TEST_TIME 72 9923
TEST_TIME 73 9996
TEST_TIME 74 10079
TEST_TIME 75 10025
TEST_TIME 76 9929
TEST_TIME 77 9855
TEST_TIME 78 9834
TEST_TIME 79 9817
TEST_TIME 80 9816
TEST_TIME 81 9779
TEST_TIME 82 9712
TEST_TIME 83 9635
TEST_TIME 84 9652
TEST_TIME 85 9653
TEST_TIME 86 9662
TEST_TIME 87 9653
TEST_TIME 88 9668
TEST_TIME 89 9690
TEST_TIME 90 9683
TEST_TIME 91 9723
TEST_TIME 92 9753
TEST_TIME 93 9766
TEST_TIME 94 9787
TEST_TIME 95 9750
TEST_TIME 96 9830
TEST_TIME 97 9840
TEST_TIME 98 9892
TEST_TIME 99 9925
TEST_TIME 100 9837
TEST_TIME 101 9786
TEST_TIME 102 9806
TEST_TIME 103 9768
TEST_TIME 104 9760
TEST_TIME 105 9718
TEST_TIME 106 9688
TEST_TIME 107 9710
TEST_TIME 108 9655
TEST_TIME 109 9646
TEST_TIME 110 9636
TEST_TIME 111 9604
TEST_TIME 112 9595
TEST_TIME 113 9634
TEST_TIME 114 9612
TEST_TIME 115 9608
TEST_TIME 116 9606
TEST_TIME 117 9635
TEST_TIME 118 9598
TEST_TIME 119 9661
TEST_TIME 120 9636

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-05-2022 16:43:21 to 03-05-2022 16:43:24.

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 1605512 1605512 0 0.00 0 30078 6 0 0
doc_list_biud 1605512 1605512 0 0.00 0 23715 27 0 0
sp_add_invoice_to_stock 5393 5308 85 1.57 35 184070 17698 0 85
sp_cancel_adding_invoice 6010 6010 0 0.00 4 63391 806 0 0
sp_cancel_client_order 1898 1898 0 0.00 4 14688 378 0 0
sp_cancel_customer_reserve 580152 580152 0 0.00 1 23674 60 0 0
sp_cancel_pay_from_customer 780 780 0 0.00 3 4162 81 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 891 44 0 0
sp_cancel_supplier_invoice 6667 6657 10 0.14 1 13114 782 0 10
sp_cancel_supplier_order 442 442 0 0.00 7 1225297 98793 0 0
sp_cancel_write_off 9523 9523 0 0.00 1 23727 105 0 0
sp_client_order 22458 22458 0 0.00 3 29961 355 0 0
sp_customer_reserve 484050 484050 0 0.00 0 109470 179 0 0
sp_fill_shopping_cart 34163 34163 0 0.00 1 9597 36 0 0
sp_fill_shopping_cart_clo_res 484046 484046 0 0.00 0 21599 12 0 0
sp_get_clo_for_invoice 5299 5299 0 0.00 0 18645 271 0 0
sp_kill_cost_storno 16743 16743 0 0.00 0 8653 52 0 0
sp_kill_qstorno_ret_qs2qd 1176926 1176926 0 0.00 0 23714 32 0 0
sp_kill_qty_storno 620709 620709 0 0.00 0 23714 70 0 0
sp_lock_selected_doc 22280 22280 0 0.00 0 11110 6 0 0
sp_make_cost_storno 30128 30128 0 0.00 0 14458 46 0 0
sp_multiply_rows_for_pdistr 30128 30128 0 0.00 0 6019 30 0 0
sp_multiply_rows_for_qdistr 22458 22458 0 0.00 0 10559 140 0 0
sp_pay_from_customer 7682 7682 0 0.00 4 27091 130 0 0
sp_pay_to_supplier 6419 6419 0 0.00 5 14888 141 0 0
sp_payment_common 14101 14101 0 0.00 1 27085 27 0 0
sp_qd_handle_on_cancel_clo 1898 1898 0 0.00 0 8215 327 0 0
sp_qd_handle_on_invoice_upd_sts 11308 11308 0 0.00 0 18093 310 0 0
sp_qd_handle_on_reserve_upd_sts 10729 10729 0 0.00 0 23400 55 0 0
sp_reserve_write_off 10729 10729 0 0.00 6 23489 178 0 0
sp_supplier_invoice 5844 5844 0 0.00 6 85738 3326 0 0
sp_supplier_order 5864 5864 0 0.00 5 36434 1364 0 0
srv_aggregate_perf_data 95 94 1 1.05 2043 465712 24537 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 762 104154 41810 0 0
srv_find_qd_qs_mism 1105458 1105458 0 0.00 0 30077 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 35 68418 997 0 0
srv_make_money_saldo 861 861 0 0.00 1 30381 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 152468 267032 228651 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 5308 5308 0 0.00 0 11110 160 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 1 6223 109 0 0
t$perf-norm:sp_cancel_client_order 1898 1898 0 0.00 0 1734 13 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1136 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 780 780 0 0.00 0 2637 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 464 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 1397 26 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 21869 1465 0 0
t$perf-norm:sp_cancel_write_off 1082 1082 0 0.00 0 940 4 0 0
t$perf-norm:sp_client_order 22458 22458 0 0.00 0 10647 17 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 4414 13 0 0
t$perf-norm:sp_pay_from_customer 7682 7682 0 0.00 0 6732 13 0 0
t$perf-norm:sp_pay_to_supplier 6419 6419 0 0.00 0 6051 11 0 0
t$perf-norm:sp_reserve_write_off 10729 10729 0 0.00 0 6962 12 0 0
t$perf-norm:sp_supplier_invoice 5844 5844 0 0.00 0 9187 17 0 0
t$perf-norm:sp_supplier_order 5864 5864 0 0.00 0 5479 18 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 1379 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 42 1 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 2218 30 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 5649 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10764 10594 170 1.57 0 12568 19 0 170
v_all_customers 41308 41308 0 0.00 0 20 1 0 0
v_all_suppliers 32634 32634 0 0.00 0 24 1 0 0
v_all_wares 44914 44914 0 0.00 0 183 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 2825 12 0 0
v_cancel_client_order 3796 3796 0 0.00 0 14649 17 0 0
v_cancel_customer_prepayment 1560 1560 0 0.00 0 4093 14 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1228 5 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 844 4 0 0
v_cancel_supplier_invoice 908 888 20 2.20 0 4207 18 0 20
v_cancel_supplier_order 884 884 0 0.00 0 12925 32 0 0
v_cancel_write_off 2164 2164 0 0.00 0 6540 16 0 0
v_max_id_clo_ord 5864 5864 0 0.00 0 4728 3 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 2326 17 0 0
v_max_id_ord_sup 5842 5842 0 0.00 0 2766 2 0 0
v_max_non_paid_invoice 1808 1808 0 0.00 0 1329 3 0 0
v_max_non_paid_realizn 3944 3944 0 0.00 0 6578 5 0 0
v_min_id_clo_ord 5864 5864 0 0.00 0 6428 6 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 4950 21 0 0
v_min_id_ord_sup 5842 5842 0 0.00 0 7435 10 0 0
v_min_non_paid_invoice 6419 6419 0 0.00 0 8155 18 0 0
v_min_non_paid_realizn 7682 7682 0 0.00 0 27081 21 0 0
v_our_firm 11082 11082 0 0.00 0 73 1 0 0
v_reserve_write_off 21458 21458 0 0.00 0 14651 12 0 0
x_lock_depdocs_on_canc_invoice 12223 12223 0 0.00 0 21873 102 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 12255 343 0 0
x_make_qstorno_1000_1200 5864 5864 0 0.00 1 36416 1278 0 0
x_make_qstorno_1200_2000 5844 5844 0 0.00 2 85236 3200 0 0
x_make_qstorno_2100_3300 484050 484050 0 0.00 0 109468 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-05-2022 16:43:25 to 03-05-2022 16:43:29.

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:12:24 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:25 29437 8192000000 9899917344 10207432704 980677920 10.691 100 90 180 0 1010735456 6057040 24000496
14:15:01 15942 8192000000 9934350288 10251886592 1016358448 11.037 100 86 172 0 1046367248 7513872 22494928
14:16:20 15491 8192000000 9968100736 10294685696 1045252304 11.315 100 86 172 0 1076201968 9072464 21877200
14:17:42 18989 8192000000 10026959616 10376466432 1112574672 11.957 100 78 155 1 1141375808 10398832 18402304
14:19:06 9730 8192000000 10082168768 10441703424 1162016480 12.422 100 65 130 1 1187971312 10853808 15101024
14:20:21 5615 8192000000 10137301952 10505695232 1220918784 12.970 100 85 169 0 1253448656 14010608 18519264
14:21:30 9776 8192000000 10170824976 10551046144 1258788752 13.319 100 86 172 1 1292126624 14031008 19306864
14:22:41 2163 8192000000 10206753360 10588758016 1289829760 13.603 100 93 186 0 1325656064 14553840 21272464
14:23:44 11437 8192000000 10210752400 10602110976 1305479680 13.745 100 49 96 2 1325035168 7966000 11589488
14:24:57 4406 8192000000 10207320816 10610544640 1312796656 13.811 100 41 82 1 1330626896 8248208 9582032
14:26:04 6189 8192000000 10213041280 10624065536 1321730672 13.892 100 28 56 1 1335599856 6403328 7465856
14:27:11 41455 8192000000 10210926160 10635313152 1325720464 13.928 100 23 46 0 1336406736 4971152 5715120
14:28:54 7396 8192000000 10228698544 10647248896 1334154384 14.005 100 26 52 0 1345621088 5362208 6104496
14:30:02 7847 8192000000 10230258992 10650484736 1340457392 14.062 100 13 26 1 1348344912 4986960 2900560
14:31:12 27191 8192000000 10215111120 10629263360 1277366496 13.489 95 26 51 1 1289197152 5703408 6127248
14:32:46 7736 8192000000 10205620064 10621624320 1281172832 13.524 97 21 42 1 1292084560 6075168 4836560
14:33:56 6005 8192000000 10168503632 10572320768 1236250336 13.112 96 13 26 0 1243811296 4837728 2723232
14:35:02 8618 8192000000 10140025056 10529300480 1199916288 12.776 95 29 58 2 1210969728 4284752 6768688
14:36:12 11179 8192000000 10149889952 10552414208 1240188832 13.148 98 20 40 1 1250252016 5417760 4645424
14:37:23 16645 8192000000 10149085696 10545655808 1203159568 12.806 95 40 76 0 1218369664 6750768 8459328
14:38:45 5888 8192000000 10174184448 10583764992 1282200224 13.533 100 35 70 1 1296392272 6037792 8154256
14:39:52 13197 8192000000 10186465664 10601394176 1296933440 13.667 100 16 32 0 1306478032 5517296 4027296
14:41:06 78899 8192000000 10207877872 10623180800 1311550080 13.800 100 36 72 2 1325678224 4881776 9246368
14:43:34 19571 8192000000 10250829376 10661490688 1341772368 14.073 100 48 96 0 1362196336 10082016 10341952
14:44:59 12909 8192000000 10259542672 10680287232 1356858240 14.209 100 43 85 1 1375790528 9231360 9700928
14:46:16 10437 8192000000 10277214160 10700304384 1368239088 14.311 100 49 98 1 1396187504 15578032 12370384
14:48:02 4767 8192000000 10295307776 10714148864 1381357760 14.429 100 46 91 0 1404115280 11954448 10803072
14:49:11 6829 8192000000 10305549952 10728390656 1392109600 14.525 100 51 102 1 1420025824 16396064 11520160
14:50:23 7956 8192000000 10310990128 10739421184 1399819584 14.593 100 40 78 1 1426684176 17622112 9242480
14:51:36 11317 8192000000 10324828352 10743746560 1396221616 14.561 100 62 121 1 1431462160 21210592 14029952
14:52:52 13180 8192000000 10325790512 10756481024 1403840656 14.629 100 43 86 0 1439671104 25872432 9958016
14:54:10 8902 8192000000 10341473888 10759114752 1403019808 14.622 100 81 162 3 1451853632 30078464 18755360
14:55:31 7026 8192000000 10351299216 10768470016 1408378736 14.670 100 71 142 1 1452371888 25547136 18446016
14:56:44 10245 8192000000 10351176144 10770821120 1410290256 14.687 100 76 152 2 1458111776 28659248 19162272
14:58:01 2184 8192000000 10326001456 10732871680 1376803296 14.388 100 82 162 0 1429250064 31897360 20549408
14:59:07 5741 8192000000 10337307040 10751381504 1387307840 14.482 100 61 121 3 1442263216 39359184 15596192
15:00:19 2370 8192000000 10342181520 10748751872 1379584896 14.413 99 84 165 2 1440701296 40352832 20763568
15:01:28 2800 8192000000 10348415504 10752172032 1373596960 14.359 98 71 142 0 1435852896 43928656 18327280
15:02:38 4834 8192000000 10334800528 10738491392 1363519632 14.269 99 73 146 0 1431261632 48991648 18750352
15:03:52 4444 8192000000 10334829840 10746372096 1378560032 14.404 100 68 135 0 1437440496 41081888 17798576
15:05:06 5332 8192000000 10350174272 10771533824 1396879584 14.567 100 47 93 2 1451600656 41810256 12910816
15:06:15 7951 8192000000 10348166144 10769465344 1394242096 14.544 100 49 97 1 1452964112 45722128 12999888
15:07:26 10944 8192000000 10340251264 10768928768 1392282544 14.526 100 39 78 0 1450291440 47068224 10940672
15:08:42 17139 8192000000 10324880784 10762268672 1371449904 14.340 100 23 47 1 1428881520 50160432 7271184
15:10:09 40201 8192000000 10314591552 10760138752 1361565712 14.251 100 23 46 0 1433896528 64972304 7358512
15:11:51 761 8192000000 10345219328 10759684096 1375988688 14.381 100 91 180 4 1446909520 46962160 23958672
15:12:54 48548 8192000000 10286152368 10729951232 1365926560 14.291 99 19 38 0 1380050752 10052960 4071232
15:14:43 75855 8192000000 10294167488 10740215808 1369403120 14.322 99 8 16 0 1383479232 12597520 1478592
15:17:05 104153 8192000000 10285861984 10736103424 1332009008 13.985 96 20 40 1 1347870016 11412992 4448016
15:20:16 40389 8192000000 10304625664 10743128064 1384123216 14.453 100 37 72 1 1404556544 11403696 9029632
15:22:01 66737 8192000000 10306534848 10752929792 1385982816 14.470 100 9 18 0 1399620800 11336896 2301088
15:24:11 85288 8192000000 10310090032 10763186176 1384077952 14.453 99 31 62 1 1402142240 10713264 7351024
15:26:39 33722 8192000000 10311710432 10759987200 1374809296 14.370 98 40 81 0 1391081840 6977088 9295456
15:28:14 61049 8192000000 10299768176 10747584512 1382662576 14.440 99 23 44 1 1396991232 8642864 5685792
15:30:20 33691 8192000000 10304208256 10762072064 1377003056 14.390 98 22 44 1 1394038192 11463984 5571152
15:31:57 47429 8192000000 10280187344 10745167872 1368440176 14.313 99 27 53 2 1388412608 13223536 6748896
15:33:46 47754 8192000000 10291951632 10758066176 1390457696 14.510 100 9 18 1 1402003424 9778048 1767680
15:35:38 82006 8192000000 10286335664 10748997632 1368142640 14.310 98 12 24 1 1379883216 8921088 2819488
15:38:04 73748 8192000000 10271561760 10736898048 1320796352 13.884 95 13 25 0 1330382496 7117872 2468272
15:40:19 27544 8192000000 10278711200 10740752384 1366512912 14.296 99 7 14 1 1376470656 8258720 1699024
15:41:47 98518 8192000000 10284305472 10764722176 1363259312 14.267 98 6 11 1 1378113120 13709136 1144672
15:44:28 49111 8192000000 10301182304 10763231232 1361732672 14.253 97 15 30 0 1376911968 11461664 3717632
15:46:18 42248 8192000000 10252767040 10714038272 1291642368 13.619 94 13 27 1 1306019712 11574032 2803312
15:48:02 53117 8192000000 10268315088 10719526912 1309927856 13.785 95 20 40 0 1324514976 10139408 4447712
15:49:58 44781 8192000000 10296218400 10740449280 1353098880 14.175 99 24 46 2 1378039120 19626912 5313328
15:51:49 39279 8192000000 10287026224 10728345600 1341442192 14.070 98 14 27 0 1355933456 11944432 2546832
15:53:29 23864 8192000000 10284506544 10719543296 1336526000 14.026 98 42 83 1 1355850096 9639456 9684640
15:54:57 30769 8192000000 10296437840 10736111616 1361858512 14.254 100 27 54 1 1386097968 18337920 5901536
15:56:35 48661 8192000000 10282694032 10723237888 1351566448 14.162 99 11 22 0 1363507216 9468784 2471984
15:58:24 91138 8192000000 10279000480 10725228544 1353707504 14.181 99 24 48 0 1366537136 7409568 5420064
16:02:09 61406 8192000000 10269811376 10734944256 1369958016 14.327 100 6 12 0 1382435552 10918160 1559376
16:04:11 73847 8192000000 10273740880 10732875776 1353965536 14.183 98 10 20 0 1369334560 12923888 2445136
16:06:25 57254 8192000000 10277918576 10753462272 1380334384 14.420 100 12 24 0 1396009072 12621440 3053248
16:08:23 55122 8192000000 10276632928 10746863616 1346769360 14.118 97 21 42 1 1365126432 13113104 5243968
16:10:44 69643 8192000000 10292185376 10756345856 1344309648 14.096 97 16 32 1 1361114736 13668912 3136176
16:12:58 51297 8192000000 10303967248 10760138752 1358403984 14.223 97 26 52 1 1374560112 10633696 5522432
16:14:49 67244 8192000000 10300456432 10759880704 1355043232 14.193 97 14 28 0 1368615104 10547408 3024464
16:16:57 17670 8192000000 10310633056 10764849152 1391677024 14.521 99 49 98 1 1409028784 5831968 11519792
16:18:20 88171 8192000000 10310131216 10766553088 1348506624 14.134 97 18 36 0 1370185360 17792656 3886080
16:20:51 77566 8192000000 10290926656 10750951424 1360701328 14.244 97 13 26 0 1379814656 15976608 3136720
16:23:11 90536 8192000000 10304035840 10764673024 1387676208 14.485 99 30 60 0 1405377824 10675616 7026000
16:26:07 35897 8192000000 10279641584 10735984640 1325363936 13.925 95 16 32 0 1337968176 8986624 3617616
16:27:43 66652 8192000000 10284585360 10745651200 1368819920 14.316 99 9 18 0 1382194400 11338400 2036080
16:29:50 72751 8192000000 10280607728 10743549952 1373800288 14.361 99 15 31 0 1385376576 7707872 3868416
16:32:27 85247 8192000000 10266068640 10714730496 1282966928 13.540 93 18 36 0 1294212944 7197952 4048064
16:34:53 60308 8192000000 10253936080 10707701760 1313322240 13.816 97 21 40 1 1332113488 14151184 4640064
16:36:54 31879 8192000000 10217061792 10654494720 1267490416 13.399 94 28 56 2 1285021920 11645520 5885984
16:38:26 50995 8192000000 10249936560 10705678336 1314474768 13.827 98 11 22 1 1329337680 12196032 2666880
16:40:17 20711 8192000000 10265745088 10705633280 1338717056 14.046 99 35 70 0 1356644256 10128560 7798640
16:41:38 62665 8192000000 9248314272 9521864704 399189968 4.646 30 6 12 0 406524688 5828928 1505792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-05-2022 16:43:31 to 03-05-2022 16:43:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 85 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-05-2022 16:43:47 to 03-05-2022 16:43:49.

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          250103
MON$OLDEST_ACTIVE               250104
MON$OLDEST_SNAPSHOT             250104
MON$NEXT_TRANSACTION            250104
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                       2056160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue May  3 16:42:42 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

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