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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15911380 kB
MemAvailable 31162180 kB
Buffers 0 kB
Cached 15759144 kB
SwapCached 2492 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-06-27 19:38:25
DTS_END                         2022-06-27 19:38:25.6350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55988
TRN_ID                          247336
ATT_ID                          657
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: 9744.48 19619 1169337
customer order: creation 179.39 372 21527
customer order: refuse 15.38 385 1846
order to supplier: creation 47.22 1283 5666
order to supplier: removal 3.65 105100 438
invoice (draft): creation 46.08 3256 5529
invoice (draft): removal 57.47 789 6896
invoice accept: apply 42.26 17178 5071
invoice accept: cancel 52.09 772 6251
customer reserve: creation 3949.13 169 473896
customer reserve: removal 5046.41 59 605569
realization accept: apply 86.23 198 10347
realization accept: cancel 88.03 107 10563
payment from customer: creation 60.01 136 7201
payment from customer: removal 6.00 72 720
payment to supplier: creation 49.72 136 5966
payment to supplier: removal 3.74 90 449
service: total inventory turnovers 4.63 1236 556
service: total monetary turnovers 6.18 21 741
service: aggregate perf. data 0.84 18139 101
service: refresh index statistics 0.03 242882 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-06-2022 19:39:23 to 27-06-2022 19:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2109
TEST_TIME 2 4058
TEST_TIME 3 3769
TEST_TIME 4 3893
TEST_TIME 5 4314
TEST_TIME 6 4129
TEST_TIME 7 4343
TEST_TIME 8 4127
TEST_TIME 9 4802
TEST_TIME 10 5240
TEST_TIME 11 5398
TEST_TIME 12 5645
TEST_TIME 13 6216
TEST_TIME 14 6298
TEST_TIME 15 6568
TEST_TIME 16 7363
TEST_TIME 17 7525
TEST_TIME 18 7867
TEST_TIME 19 8190
TEST_TIME 20 8424
TEST_TIME 21 8650
TEST_TIME 22 9153
TEST_TIME 23 9257
TEST_TIME 24 9313
TEST_TIME 25 9500
TEST_TIME 26 9782
TEST_TIME 27 10056
TEST_TIME 28 10518
TEST_TIME 29 10399
TEST_TIME 30 10697
TEST_TIME 31 10950
TEST_TIME 32 10704
TEST_TIME 33 10745
TEST_TIME 34 10693
TEST_TIME 35 10520
TEST_TIME 36 10439
TEST_TIME 37 10443
TEST_TIME 38 10327
TEST_TIME 39 10618
TEST_TIME 40 10638
TEST_TIME 41 10609
TEST_TIME 42 10493
TEST_TIME 43 10674
TEST_TIME 44 10527
TEST_TIME 45 10297
TEST_TIME 46 10191
TEST_TIME 47 10216
TEST_TIME 48 10264
TEST_TIME 49 10155
TEST_TIME 50 10088
TEST_TIME 51 10152
TEST_TIME 52 10093
TEST_TIME 53 10043
TEST_TIME 54 10338
TEST_TIME 55 10261
TEST_TIME 56 10176
TEST_TIME 57 10069
TEST_TIME 58 10092
TEST_TIME 59 9956
TEST_TIME 60 10009
TEST_TIME 61 9913
TEST_TIME 62 9929
TEST_TIME 63 9867
TEST_TIME 64 9855
TEST_TIME 65 9893
TEST_TIME 66 9856
TEST_TIME 67 9842
TEST_TIME 68 9885
TEST_TIME 69 9934
TEST_TIME 70 9895
TEST_TIME 71 9882
TEST_TIME 72 9902
TEST_TIME 73 9903
TEST_TIME 74 9913
TEST_TIME 75 9927
TEST_TIME 76 9973
TEST_TIME 77 10062
TEST_TIME 78 10105
TEST_TIME 79 10037
TEST_TIME 80 9963
TEST_TIME 81 9969
TEST_TIME 82 9906
TEST_TIME 83 9913
TEST_TIME 84 9877
TEST_TIME 85 9835
TEST_TIME 86 9818
TEST_TIME 87 9750
TEST_TIME 88 9732
TEST_TIME 89 9791
TEST_TIME 90 9830
TEST_TIME 91 9782
TEST_TIME 92 9801
TEST_TIME 93 9764
TEST_TIME 94 9874
TEST_TIME 95 9936
TEST_TIME 96 9905
TEST_TIME 97 9906
TEST_TIME 98 9879
TEST_TIME 99 9916
TEST_TIME 100 9987
TEST_TIME 101 9979
TEST_TIME 102 9999
TEST_TIME 103 9960
TEST_TIME 104 9913
TEST_TIME 105 9900
TEST_TIME 106 9887
TEST_TIME 107 9848
TEST_TIME 108 9800
TEST_TIME 109 9778
TEST_TIME 110 9754
TEST_TIME 111 9746
TEST_TIME 112 9690
TEST_TIME 113 9663
TEST_TIME 114 9747
TEST_TIME 115 9778
TEST_TIME 116 9757
TEST_TIME 117 9742
TEST_TIME 118 9706
TEST_TIME 119 9759
TEST_TIME 120 9747

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-06-2022 19:39:26 to 27-06-2022 19:39:30.

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 1609180 1609180 0 0.00 0 30056 6 0 0
doc_list_biud 1609179 1609179 0 0.00 0 23508 28 0 0
sp_add_invoice_to_stock 5192 5071 121 2.33 34 149930 17179 0 121
sp_cancel_adding_invoice 6251 6251 0 0.00 4 70225 772 0 0
sp_cancel_client_order 1846 1846 0 0.00 4 18160 386 0 0
sp_cancel_customer_reserve 605569 605569 0 0.00 1 21182 59 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 5412 72 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 4477 90 0 0
sp_cancel_supplier_invoice 6904 6896 8 0.11 2 20045 789 0 8
sp_cancel_supplier_order 438 438 0 0.00 6 1019257 105100 0 0
sp_cancel_write_off 10563 10563 0 0.00 1 17652 108 0 0
sp_client_order 21527 21527 0 0.00 3 18063 373 0 0
sp_customer_reserve 473896 473896 0 0.00 0 70495 169 0 0
sp_fill_shopping_cart 32711 32711 0 0.00 1 15174 36 0 0
sp_fill_shopping_cart_clo_res 473893 473893 0 0.00 0 17206 12 0 0
sp_get_clo_for_invoice 5066 5066 0 0.00 0 22201 264 0 0
sp_kill_cost_storno 17983 17983 0 0.00 0 20323 56 0 0
sp_kill_qstorno_ret_qs2qd 1229035 1229035 0 0.00 0 20429 32 0 0
sp_kill_qty_storno 646975 646975 0 0.00 0 23507 70 0 0
sp_lock_selected_doc 21426 21426 0 0.00 0 8515 5 0 0
sp_make_cost_storno 28580 28580 0 0.00 0 30053 56 0 0
sp_multiply_rows_for_pdistr 28580 28580 0 0.00 0 21042 34 0 0
sp_multiply_rows_for_qdistr 21527 21527 0 0.00 0 18048 175 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 21067 136 0 0
sp_pay_to_supplier 5966 5966 0 0.00 5 30065 136 0 0
sp_payment_common 13167 13167 0 0.00 2 20910 34 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 0 17974 327 0 0
sp_qd_handle_on_invoice_upd_sts 11316 11316 0 0.00 0 23506 343 0 0
sp_qd_handle_on_reserve_upd_sts 10347 10347 0 0.00 0 18694 58 0 0
sp_reserve_write_off 10347 10347 0 0.00 6 21129 198 0 0
sp_supplier_invoice 5529 5529 0 0.00 7 100805 3257 0 0
sp_supplier_order 5666 5666 0 0.00 5 33264 1283 0 0
srv_aggregate_perf_data 101 101 0 0.00 2970 169981 18140 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 3928 128506 42734 0 0
srv_find_qd_qs_mism 1120916 1120916 0 0.00 0 10892 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 42 83672 1237 0 0
srv_make_money_saldo 741 741 0 0.00 1 277 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 109314 368346 242883 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5071 5071 0 0.00 0 5083 132 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 6914 109 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 2836 11 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 1010 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 1013 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 290 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 2433 18 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 23583 1619 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1176 9 0 0
t$perf-norm:sp_client_order 21527 21527 0 0.00 0 12544 16 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 1593 5 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 2648 7 0 0
t$perf-norm:sp_pay_to_supplier 5966 5966 0 0.00 0 2701 6 0 0
t$perf-norm:sp_reserve_write_off 10347 10347 0 0.00 0 12502 11 0 0
t$perf-norm:sp_supplier_invoice 5529 5529 0 0.00 0 9360 15 0 0
t$perf-norm:sp_supplier_order 5666 5666 0 0.00 0 6338 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 665 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 671 16 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 1340 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 241 64 0 0
v_add_invoice_to_stock 10370 10128 242 2.33 0 16975 24 0 242
v_all_customers 39332 39332 0 0.00 0 109 1 0 0
v_all_suppliers 31114 31114 0 0.00 0 30 1 0 0
v_all_wares 43054 43054 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 40 5 0 0
v_cancel_client_order 3692 3692 0 0.00 0 13207 20 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1105 6 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 2958 12 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 2724 18 0 0
v_cancel_supplier_invoice 894 878 16 1.78 0 3318 23 0 16
v_cancel_supplier_order 876 876 0 0.00 0 3018 10 0 0
v_cancel_write_off 1968 1968 0 0.00 0 5370 16 0 0
v_max_id_clo_ord 5661 5661 0 0.00 0 7580 2 0 0
v_max_id_clo_res 975 975 0 0.00 0 2258 17 0 0
v_max_id_ord_sup 5522 5522 0 0.00 0 4863 5 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 2800 6 0 0
v_max_non_paid_realizn 3742 3742 0 0.00 0 3290 5 0 0
v_min_id_clo_ord 5661 5661 0 0.00 0 3906 6 0 0
v_min_id_clo_res 975 975 0 0.00 0 7827 18 0 0
v_min_id_ord_sup 5522 5522 0 0.00 0 6118 9 0 0
v_min_non_paid_invoice 5966 5966 0 0.00 0 17780 18 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 20904 33 0 0
v_our_firm 10640 10640 0 0.00 0 20 1 0 0
v_reserve_write_off 20694 20694 0 0.00 0 17242 10 0 0
x_lock_depdocs_on_canc_invoice 12708 12708 0 0.00 0 19559 118 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 14929 381 0 0
x_make_qstorno_1000_1200 5666 5666 0 0.00 2 33236 1205 0 0
x_make_qstorno_1200_2000 5529 5529 0 0.00 3 100784 3128 0 0
x_make_qstorno_2100_3300 473896 473896 0 0.00 0 70494 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-06-2022 19:39:31 to 27-06-2022 19:39:35.

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:29 4 8192000000 8804626288 8817774592 10209808 0.124 3 2 3 0 10352112 67360 74944
17:09:30 32610 8192000000 9910639824 10224115712 988563392 10.768 100 88 176 0 1016910400 5873632 22473376
17:11:07 11997 8192000000 9952118064 10272579584 1037555120 11.241 100 88 176 0 1068228064 8156496 22516448
17:12:25 23691 8192000000 9982066672 10315276288 1066571600 11.519 100 86 172 1 1097736416 9475744 21689072
17:14:00 14063 8192000000 10055044048 10403332096 1135451360 12.173 100 80 161 1 1165004464 10854784 18698320
17:15:21 10405 8192000000 10093957248 10450743296 1174923632 12.543 100 80 161 1 1205869744 13040144 17905968
17:16:35 4016 8192000000 10132227040 10503933952 1221777504 12.978 100 79 158 1 1253881648 14162912 17941232
17:17:41 3989 8192000000 10174587840 10550861824 1259664304 13.327 100 88 174 1 1292281888 13678048 18939536
17:18:47 2412 8192000000 10221665424 10603749376 1301095776 13.705 100 87 174 0 1333534784 13164816 19274192
17:19:50 2243 8192000000 10207484096 10600943616 1303260128 13.725 100 60 121 0 1328491840 11719600 13512112
17:20:54 5455 8192000000 10207109552 10614480896 1315061520 13.832 100 49 98 1 1334361296 8345056 10954720
17:22:01 39607 8192000000 10208882944 10631340032 1321310736 13.889 100 22 44 1 1332270640 5820448 5139456
17:23:41 8057 8192000000 10216940432 10637053952 1330615024 13.973 100 19 38 0 1340299200 5387600 4296576
17:24:50 6071 8192000000 10221983376 10643402752 1335256640 14.015 100 23 46 1 1346489024 5988688 5243696
17:25:57 16377 8192000000 10214124272 10630443008 1304135280 13.733 98 25 50 1 1315867424 6029920 5702224
17:27:14 4183 8192000000 10222511760 10636496896 1326190688 13.933 100 39 78 3 1343811456 9424816 8195952
17:28:22 5962 8192000000 10185541360 10592915456 1286063520 13.568 98 30 58 1 1298617328 6269680 6284128
17:29:30 7123 8192000000 10163878384 10571157504 1253049376 13.266 98 23 46 1 1264584864 5884688 5650800
17:30:39 58518 8192000000 10127897312 10525052928 1154710592 12.354 92 24 48 0 1164731232 4433600 5587040
17:32:42 9363 8192000000 10153712688 10552119296 1233754912 13.089 98 21 42 0 1244091360 5758624 4577824
17:33:54 10267 8192000000 10181973632 10582605824 1279779040 13.511 100 27 54 0 1291520448 5527904 6213504
17:35:07 63327 8192000000 10177118592 10599149568 1294736672 13.647 100 39 78 0 1309833856 5359904 9737280
17:37:10 4492 8192000000 10205186768 10616270848 1307638288 13.765 100 45 88 2 1326341968 8575328 10128352
17:38:18 4466 8192000000 10225871088 10636066816 1325330816 13.925 100 27 54 0 1337048176 4660976 7056384
17:39:30 33266 8192000000 10264002720 10672873472 1349459088 14.143 100 49 97 1 1373961712 13903408 10599216
17:41:13 75709 8192000000 10279457424 10706472960 1371801600 14.343 100 40 80 3 1397223568 15511104 9910864
17:43:38 30324 8192000000 10282261072 10714206208 1367655008 14.306 99 44 88 0 1399652144 21064656 10932480
17:45:20 25088 8192000000 10301579904 10733068288 1389006496 14.497 100 35 70 3 1418357536 20618656 8732384
17:47:01 9462 8192000000 10332779632 10750955520 1398818352 14.584 100 66 130 1 1440614624 26366080 15430192
17:48:14 7509 8192000000 10341587536 10763509760 1408579568 14.671 100 68 135 2 1452400144 27263824 16556752
17:49:32 8447 8192000000 10334662304 10755231744 1397185664 14.570 99 55 110 0 1444127936 32687408 14254864
17:50:51 4721 8192000000 10343704384 10755338240 1386301808 14.473 99 71 142 0 1443455632 39210944 17942880
17:51:58 4759 8192000000 10338297824 10752114688 1383601888 14.449 99 70 139 0 1438328912 36662960 18064064
17:53:08 3927 8192000000 10350515856 10760015872 1395752480 14.557 100 71 140 2 1460082672 45245984 19084208
17:54:15 4907 8192000000 10347068048 10761150464 1386581936 14.475 99 78 152 1 1443163328 37348032 19233360
17:55:25 8364 8192000000 10337722800 10758590464 1390072464 14.507 100 55 110 1 1445340800 40629152 14639184
17:56:39 6583 8192000000 10334654544 10757107712 1387359904 14.482 100 47 91 3 1438993600 39379760 12253936
17:57:50 6784 8192000000 10338893088 10766970880 1383525936 14.448 99 48 96 1 1437458128 40902592 13029600
17:59:02 13882 8192000000 10308120032 10740420608 1369044496 14.318 100 43 84 3 1422969392 42513360 11411536
18:00:23 8933 8192000000 10311769712 10748329984 1367322320 14.303 100 39 78 1 1419237696 42194944 9720432
18:01:42 29924 8192000000 10328135040 10762899456 1387538096 14.484 100 20 40 1 1433170896 40030752 5602048
18:03:16 13177 8192000000 10333573872 10774089728 1375266432 14.374 99 24 47 1 1430529632 48397136 6866064
18:04:36 29348 8192000000 10310850096 10748166144 1348626544 14.135 99 17 33 4 1400922320 47668688 4627088
18:06:15 12721 8192000000 10304334656 10726191104 1350280816 14.150 100 51 102 3 1403326784 39824800 13221168
18:07:29 37266 8192000000 10277151808 10721755136 1351939440 14.165 100 18 36 1 1387560864 30697440 4923984
18:09:08 74346 8192000000 10289612864 10719768576 1346356256 14.115 98 10 19 1 1360904432 12912624 1635552
18:11:23 53958 8192000000 10275028080 10718621696 1356912560 14.210 99 14 28 0 1368752224 8336736 3502928
18:13:41 48285 8192000000 10308498176 10745044992 1369410368 14.322 99 25 50 0 1384372960 8920176 6042416
18:15:31 63325 8192000000 10320954000 10749575168 1383883488 14.451 100 38 77 0 1408810992 15557264 9370240
18:17:41 128506 8192000000 10295953376 10749825024 1357687648 14.217 98 7 13 2 1379820960 20328704 1804608
18:21:01 105438 8192000000 10299576096 10752401408 1374639344 14.369 99 15 30 0 1395227856 17061744 3526768
18:23:47 41881 8192000000 10308479440 10759835648 1393131664 14.534 100 41 79 0 1412180368 8979824 10068880
18:25:30 26752 8192000000 10315288160 10764255232 1389295824 14.500 99 37 74 1 1415587136 17917952 8373360
18:27:03 41204 8192000000 10307504128 10765864960 1403516480 14.626 100 12 24 0 1415658448 9319616 2822352
18:28:47 39125 8192000000 10321047264 10769510400 1387678016 14.485 99 23 46 1 1406052048 12630080 5743952
18:30:49 64604 8192000000 10334144304 10785742848 1397717008 14.575 99 46 92 0 1427537952 18809440 11011504
18:33:15 78306 8192000000 10276593680 10732175360 1285877776 13.567 92 24 48 1 1300836848 10119488 4839584
18:35:37 41045 8192000000 10279711168 10744848384 1361495504 14.251 99 11 22 0 1381712272 17575216 2641552
18:37:21 46856 8192000000 10280930416 10738409472 1362695712 14.262 99 37 73 0 1381141904 9781584 8664608
18:39:13 26178 8192000000 10278165376 10734628864 1372375968 14.348 99 19 38 0 1383091872 6662064 4053840
18:40:53 38979 8192000000 10272150688 10741374976 1376157168 14.382 100 17 34 0 1392920368 12747264 4015936
18:42:42 73719 8192000000 10268614896 10749243392 1359828992 14.236 99 6 12 1 1374069728 13022224 1218512
18:44:56 47276 8192000000 10284990448 10742235136 1351802032 14.164 98 17 34 1 1367006912 11691696 3513184
18:46:47 47851 8192000000 10283195104 10741977088 1353630768 14.180 98 14 28 0 1366003952 9198560 3174624
18:48:35 35318 8192000000 10279214768 10728099840 1336493136 14.026 97 24 48 0 1353027712 11191296 5343280
18:50:11 34857 8192000000 10259011840 10701176832 1321350352 13.889 98 35 68 1 1342410800 13942848 7117600
18:51:52 26478 8192000000 10268105472 10708598784 1327245600 13.942 98 35 70 0 1347651312 12136224 8269488
18:53:26 54850 8192000000 10281052832 10727981056 1362448064 14.259 100 33 65 2 1393428608 23357232 7623312
18:55:30 61336 8192000000 10258424784 10710736896 1334770144 14.010 99 6 12 0 1350458592 14696128 992320
18:57:37 95583 8192000000 10258953856 10711076864 1330500624 13.972 98 46 93 0 1349769744 8676256 10592864
19:00:21 34021 8192000000 10267598736 10727874560 1346174480 14.113 99 13 26 0 1366349712 16881968 3293264
19:01:56 61191 8192000000 10277802848 10741882880 1355452992 14.197 99 13 26 0 1374569232 15939696 3176544
19:04:00 48442 8192000000 10273504800 10735058944 1366228768 14.293 100 12 24 0 1388911376 19890528 2792080
19:05:53 57330 8192000000 10272683504 10739838976 1363675952 14.270 99 15 30 1 1382270816 14806352 3788512
19:08:05 79236 8192000000 10292714064 10741886976 1368614800 14.315 99 14 28 1 1386233536 14611904 3006832
19:10:25 58177 8192000000 10282819056 10738364416 1348965280 14.138 97 17 34 1 1365677552 12907280 3804992
19:12:30 90358 8192000000 10282530144 10742751232 1316323360 13.843 95 13 26 0 1338390704 19051104 3016240
19:15:20 12132 8192000000 10306421696 10745245696 1376364432 14.384 99 47 94 0 1396388256 9971968 10051856
19:16:33 29115 8192000000 10285754544 10726920192 1341719984 14.073 98 39 79 1 1363511216 12854880 8936352
19:18:12 89015 8192000000 10307585664 10758656000 1369293024 14.321 99 13 26 0 1385739168 14035632 2410512
19:20:42 41572 8192000000 10288348256 10733510656 1357101968 14.211 98 34 68 0 1372090736 7592192 7396576
19:22:30 92554 8192000000 10290179520 10746310656 1378503968 14.403 100 19 38 1 1391330368 8592976 4233424
19:25:15 13577 8192000000 10285450448 10756001792 1381966624 14.434 100 11 22 0 1397909936 12626864 3316448
19:26:29 56567 8192000000 10284680832 10750418944 1387766000 14.486 100 13 26 0 1401086064 9691664 3628400
19:28:26 85005 8192000000 10258222896 10709458944 1278434560 13.499 93 20 40 1 1294664896 11154256 5076080
19:30:57 55654 8192000000 10271699744 10736013312 1347906736 14.129 98 15 30 0 1365947904 14261184 3779984
19:33:01 58706 8192000000 10262816592 10726801408 1324835264 13.920 97 22 44 0 1347344160 16923632 5585264
19:35:00 16010 8192000000 10277449248 10719297536 1356700080 14.208 100 33 66 1 1375614528 11047504 7866944
19:36:23 36304 8192000000 10265939760 10699661312 1286445312 13.572 95 34 68 1 1308455712 14312848 7697552
19:38:00 34252 8192000000 9026275120 9241194496 191519264 2.284 15 1 2 0 194125440 2563824 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-06-2022 19:39:37 to 27-06-2022 19:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-06-2022 19:39:52 to 27-06-2022 19:39:54.

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

Database statistics

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

Database validation

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

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

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

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

27.06.22 19:39:55. 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-06-27 19:39:58 No severe PSQL-related problems occured
27.06.22 19:39:59. End of report.