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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16440568 kB
MemAvailable 31494104 kB
Buffers 0 kB
Cached 15296464 kB
SwapCached 2660 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-13 16:38:17
DTS_END                         2022-05-13 16:38:17.7430
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/41032
TRN_ID                          244067
ATT_ID                          691
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: 9821.13 22398 1178535
customer order: creation 177.66 346 21319
customer order: refuse 15.18 356 1821
order to supplier: creation 46.08 1202 5529
order to supplier: removal 3.49 113737 419
invoice (draft): creation 46.10 3315 5532
invoice (draft): removal 57.57 770 6908
invoice accept: apply 42.72 16600 5126
invoice accept: cancel 52.09 726 6251
customer reserve: creation 3972.83 161 476740
customer reserve: removal 5101.52 61 612183
realization accept: apply 85.17 174 10220
realization accept: cancel 86.98 105 10437
payment from customer: creation 61.02 120 7322
payment from customer: removal 6.25 79 750
payment to supplier: creation 49.88 139 5986
payment to supplier: removal 3.64 90 437
service: total inventory turnovers 5.03 1076 603
service: total monetary turnovers 7.05 32 846
service: aggregate perf. data 0.85 19641 102
service: refresh index statistics 0.03 289230 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-05-2022 16:39:52 to 13-05-2022 16:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3169
TEST_TIME 2 3081
TEST_TIME 3 3951
TEST_TIME 4 4329
TEST_TIME 5 4818
TEST_TIME 6 4731
TEST_TIME 7 5085
TEST_TIME 8 5543
TEST_TIME 9 5326
TEST_TIME 10 5512
TEST_TIME 11 5733
TEST_TIME 12 6033
TEST_TIME 13 6411
TEST_TIME 14 6660
TEST_TIME 15 7203
TEST_TIME 16 7726
TEST_TIME 17 7508
TEST_TIME 18 7804
TEST_TIME 19 8311
TEST_TIME 20 8700
TEST_TIME 21 8578
TEST_TIME 22 8953
TEST_TIME 23 9024
TEST_TIME 24 9672
TEST_TIME 25 9837
TEST_TIME 26 10213
TEST_TIME 27 11241
TEST_TIME 28 11301
TEST_TIME 29 11270
TEST_TIME 30 11152
TEST_TIME 31 11160
TEST_TIME 32 11140
TEST_TIME 33 11206
TEST_TIME 34 11183
TEST_TIME 35 11069
TEST_TIME 36 10908
TEST_TIME 37 10671
TEST_TIME 38 10558
TEST_TIME 39 10614
TEST_TIME 40 10620
TEST_TIME 41 10591
TEST_TIME 42 10464
TEST_TIME 43 10342
TEST_TIME 44 10268
TEST_TIME 45 10583
TEST_TIME 46 10467
TEST_TIME 47 10337
TEST_TIME 48 10218
TEST_TIME 49 10325
TEST_TIME 50 10219
TEST_TIME 51 10235
TEST_TIME 52 10224
TEST_TIME 53 10205
TEST_TIME 54 10130
TEST_TIME 55 10042
TEST_TIME 56 10044
TEST_TIME 57 10160
TEST_TIME 58 10161
TEST_TIME 59 10212
TEST_TIME 60 10227
TEST_TIME 61 10276
TEST_TIME 62 10255
TEST_TIME 63 10343
TEST_TIME 64 10376
TEST_TIME 65 10416
TEST_TIME 66 10352
TEST_TIME 67 10406
TEST_TIME 68 10335
TEST_TIME 69 10272
TEST_TIME 70 10270
TEST_TIME 71 10188
TEST_TIME 72 10151
TEST_TIME 73 10136
TEST_TIME 74 10071
TEST_TIME 75 10094
TEST_TIME 76 10028
TEST_TIME 77 9961
TEST_TIME 78 9975
TEST_TIME 79 10068
TEST_TIME 80 10022
TEST_TIME 81 10118
TEST_TIME 82 10067
TEST_TIME 83 10071
TEST_TIME 84 10091
TEST_TIME 85 10201
TEST_TIME 86 10163
TEST_TIME 87 10199
TEST_TIME 88 10197
TEST_TIME 89 10237
TEST_TIME 90 10332
TEST_TIME 91 10236
TEST_TIME 92 10172
TEST_TIME 93 10138
TEST_TIME 94 10144
TEST_TIME 95 10084
TEST_TIME 96 10046
TEST_TIME 97 9984
TEST_TIME 98 9988
TEST_TIME 99 9942
TEST_TIME 100 9934
TEST_TIME 101 9979
TEST_TIME 102 9967
TEST_TIME 103 9934
TEST_TIME 104 10020
TEST_TIME 105 10072
TEST_TIME 106 10086
TEST_TIME 107 10032
TEST_TIME 108 10087
TEST_TIME 109 10074
TEST_TIME 110 10083
TEST_TIME 111 10047
TEST_TIME 112 9982
TEST_TIME 113 9958
TEST_TIME 114 9919
TEST_TIME 115 9887
TEST_TIME 116 9831
TEST_TIME 117 9824
TEST_TIME 118 9812
TEST_TIME 119 9835
TEST_TIME 120 9825

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-05-2022 16:39:56 to 13-05-2022 16:39:59.

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 1620174 1620174 0 0.00 0 13981 6 0 0
doc_list_biud 1620174 1620174 0 0.00 0 59812 29 0 0
sp_add_invoice_to_stock 5234 5126 108 2.06 25 168371 16601 0 108
sp_cancel_adding_invoice 6251 6251 0 0.00 4 58271 727 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 8481 357 0 0
sp_cancel_customer_reserve 612183 612183 0 0.00 1 59813 61 0 0
sp_cancel_pay_from_customer 750 750 0 0.00 2 5418 79 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 5776 90 0 0
sp_cancel_supplier_invoice 6921 6908 13 0.18 2 15439 771 0 13
sp_cancel_supplier_order 419 419 0 0.00 7 1058082 113737 0 0
sp_cancel_write_off 10437 10437 0 0.00 1 13359 106 0 0
sp_client_order 21319 21319 0 0.00 4 12817 347 0 0
sp_customer_reserve 476740 476740 0 0.00 0 70024 161 0 0
sp_fill_shopping_cart 32366 32366 0 0.00 1 15267 35 0 0
sp_fill_shopping_cart_clo_res 476737 476737 0 0.00 0 22213 13 0 0
sp_get_clo_for_invoice 5114 5114 0 0.00 0 16988 299 0 0
sp_kill_cost_storno 17875 17875 0 0.00 0 7278 59 0 0
sp_kill_qstorno_ret_qs2qd 1242130 1242130 0 0.00 0 59809 33 0 0
sp_kill_qty_storno 653349 653349 0 0.00 0 59812 71 0 0
sp_lock_selected_doc 21271 21271 0 0.00 0 7789 8 0 0
sp_make_cost_storno 28638 28638 0 0.00 0 12873 54 0 0
sp_multiply_rows_for_pdistr 28638 28638 0 0.00 0 6548 29 0 0
sp_multiply_rows_for_qdistr 21319 21319 0 0.00 0 10085 163 0 0
sp_pay_from_customer 7322 7322 0 0.00 4 7422 120 0 0
sp_pay_to_supplier 5986 5986 0 0.00 4 11913 140 0 0
sp_payment_common 13308 13308 0 0.00 1 7608 30 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 8474 311 0 0
sp_qd_handle_on_invoice_upd_sts 11361 11361 0 0.00 0 40202 330 0 0
sp_qd_handle_on_reserve_upd_sts 10220 10220 0 0.00 0 15382 57 0 0
sp_reserve_write_off 10220 10220 0 0.00 6 15412 174 0 0
sp_supplier_invoice 5532 5532 0 0.00 6 91226 3315 0 0
sp_supplier_order 5529 5529 0 0.00 5 73362 1203 0 0
srv_aggregate_perf_data 102 102 0 0.00 2171 262533 19641 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1534 119638 45696 0 0
srv_find_qd_qs_mism 1129520 1129520 0 0.00 0 9851 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 29 93464 1077 0 0
srv_make_money_saldo 846 846 0 0.00 1 10490 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 177466 412988 289231 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5126 5126 0 0.00 0 10183 172 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 4051 84 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 3413 13 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 3902 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 750 750 0 0.00 0 7471 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 714 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 4160 25 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 24296 1764 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 1548 4 0 0
t$perf-norm:sp_client_order 21319 21319 0 0.00 0 11243 17 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 5816 16 0 0
t$perf-norm:sp_pay_from_customer 7322 7322 0 0.00 0 11253 12 0 0
t$perf-norm:sp_pay_to_supplier 5986 5986 0 0.00 0 7326 10 0 0
t$perf-norm:sp_reserve_write_off 10220 10220 0 0.00 0 4628 9 0 0
t$perf-norm:sp_supplier_invoice 5532 5532 0 0.00 0 6913 21 0 0
t$perf-norm:sp_supplier_order 5529 5529 0 0.00 0 7233 19 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 2124 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 113 4 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1001 26 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 975 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10434 10218 216 2.07 0 5461 20 0 216
v_all_customers 38914 38914 0 0.00 0 56 1 0 0
v_all_suppliers 31002 31002 0 0.00 0 17 1 0 0
v_all_wares 42638 42638 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 5562 14 0 0
v_cancel_client_order 3642 3642 0 0.00 0 3981 7 0 0
v_cancel_customer_prepayment 1500 1500 0 0.00 0 3398 6 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 2762 8 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1800 10 0 0
v_cancel_supplier_invoice 938 912 26 2.77 0 5105 24 0 26
v_cancel_supplier_order 838 838 0 0.00 0 2170 12 0 0
v_cancel_write_off 1978 1978 0 0.00 0 4004 16 0 0
v_max_id_clo_ord 5529 5529 0 0.00 0 2748 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 2921 17 0 0
v_max_id_ord_sup 5518 5518 0 0.00 0 4526 3 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 825 3 0 0
v_max_non_paid_realizn 3857 3857 0 0.00 0 4775 7 0 0
v_min_id_clo_ord 5529 5529 0 0.00 0 2941 8 0 0
v_min_id_clo_res 995 995 0 0.00 0 10042 15 0 0
v_min_id_ord_sup 5518 5518 0 0.00 0 15238 10 0 0
v_min_non_paid_invoice 5986 5986 0 0.00 0 7603 24 0 0
v_min_non_paid_realizn 7322 7322 0 0.00 0 7403 20 0 0
v_our_firm 10654 10654 0 0.00 0 17 1 0 0
v_reserve_write_off 20440 20440 0 0.00 0 3811 7 0 0
x_lock_depdocs_on_canc_invoice 12703 12703 0 0.00 0 10641 117 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3227 344 0 0
x_make_qstorno_1000_1200 5529 5529 0 0.00 2 71688 1123 0 0
x_make_qstorno_1200_2000 5532 5532 0 0.00 2 89636 3192 0 0
x_make_qstorno_2100_3300 476740 476740 0 0.00 0 70019 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-05-2022 16:40:00 to 13-05-2022 16:40:04.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:21 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:22 22339 8192000000 9885678192 10193076224 969752144 10.584 100 86 172 1 998982656 5989712 23240800
14:10:50 31369 8192000000 9940646400 10263482368 1022447408 11.096 100 85 170 1 1052628752 8385168 21796176
14:12:26 12360 8192000000 9980475888 10314612736 1066756752 11.521 100 84 168 0 1098054320 10133408 21164160
14:13:42 8021 8192000000 10040115408 10385375232 1124166864 12.066 100 80 160 1 1154113728 11369296 18577568
14:14:54 11235 8192000000 10082235488 10436653056 1164877712 12.449 100 83 166 2 1197236048 13160912 19197424
14:16:11 5276 8192000000 10124496720 10490462208 1210266160 12.872 100 88 177 2 1244322800 14261216 19795424
14:17:18 3479 8192000000 10159506032 10531831808 1246209200 13.203 100 88 177 0 1278556368 13081920 19265248
14:18:23 2193 8192000000 10202628112 10582110208 1283570112 13.546 100 92 184 0 1318600960 14704752 20326096
14:19:26 19480 8192000000 10215740128 10610192384 1302420944 13.717 100 81 163 1 1333020336 12742288 17857104
14:20:48 6415 8192000000 10206361632 10617810944 1318248240 13.861 100 34 68 2 1333426512 7238976 7939296
14:21:55 20451 8192000000 10208052448 10630737920 1331557840 13.981 100 15 30 1 1340421472 5127856 3735776
14:23:19 65387 8192000000 10228901488 10653417472 1340484608 14.062 100 28 56 0 1353029728 5154704 7390416
14:25:26 4333 8192000000 10244041072 10661593088 1344931856 14.102 100 27 55 2 1357587312 6466880 6188576
14:26:31 9903 8192000000 10230339792 10654961664 1309570112 13.782 97 24 48 1 1321050112 5948912 5531088
14:27:44 60884 8192000000 10245969216 10657013760 1328186592 13.951 99 38 74 2 1342142928 5413296 8543040
14:29:46 6456 8192000000 10186772912 10601947136 1286847984 13.575 99 20 40 1 1301872288 9670208 5354096
14:30:52 11061 8192000000 10120567920 10517258240 1184894512 12.636 95 14 28 0 1193153200 5026592 3232096
14:32:04 6401 8192000000 10133296752 10538774528 1227284608 13.029 98 21 42 1 1238196032 6230496 4680928
14:33:10 32750 8192000000 10149416752 10557960192 1263755728 13.364 100 30 62 1 1276274528 5466816 7051984
14:34:45 4377 8192000000 10185405680 10590142464 1282084944 13.532 100 33 66 0 1301844976 11551824 8208208
14:35:51 7401 8192000000 10183969168 10600861696 1298130544 13.678 100 25 50 2 1309899424 4892080 6876800
14:36:59 8804 8192000000 10193472064 10609352704 1303688864 13.729 100 16 32 1 1313234272 5879328 3666080
14:38:08 119638 8192000000 10235153264 10649632768 1326721376 13.938 100 40 79 0 1344304592 7886560 9696656
14:41:40 37825 8192000000 10266432576 10682646528 1355586976 14.198 100 37 74 1 1378714064 14274944 8852144
14:43:22 20025 8192000000 10275625648 10694615040 1367313712 14.303 100 38 76 2 1388200752 12069872 8817168
14:44:44 5310 8192000000 10307670224 10719195136 1384079680 14.453 100 63 126 0 1416153104 17346432 14726992
14:45:55 19254 8192000000 10313442528 10736001024 1395993440 14.559 100 57 109 3 1427646032 18169344 13483248
14:47:32 6870 8192000000 10336818192 10753814528 1407943760 14.666 100 68 136 1 1447049744 22900384 16205600
14:48:49 7657 8192000000 10345387952 10766073856 1412638592 14.707 100 64 127 1 1452646880 24711072 15297216
14:50:01 3488 8192000000 10364961152 10779172864 1420693840 14.779 100 83 166 3 1472082256 31414464 19973952
14:51:10 3527 8192000000 10386453184 10802933760 1434388272 14.900 100 79 158 2 1491752368 38094768 19269328
14:52:22 3023 8192000000 10367915408 10775105536 1408615072 14.672 100 84 168 1 1467444544 36738480 22090992
14:53:29 1533 8192000000 10361474400 10770866176 1401759264 14.611 100 84 168 2 1463430352 40542304 21128784
14:54:36 9216 8192000000 10334996272 10760089600 1398023888 14.577 100 46 92 1 1444164096 33984880 12155328
14:55:56 2686 8192000000 10360892160 10759540736 1380663664 14.422 99 91 180 0 1449279072 45842368 22773040
14:57:03 5511 8192000000 10358831696 10768982016 1393697264 14.539 100 86 173 1 1463740192 46791488 23251440
14:58:12 4792 8192000000 10350561104 10768945152 1396927840 14.568 100 60 120 5 1454662800 41817392 15917568
14:59:20 8750 8192000000 10347201056 10772082688 1375035328 14.372 98 44 88 2 1430134480 42818592 12280560
15:00:31 24215 8192000000 10328013216 10755698688 1370715216 14.333 99 32 64 0 1423242512 43326352 9200944
15:01:57 25237 8192000000 10316733136 10762612736 1359974640 14.237 99 31 62 0 1421632864 52303712 9354512
15:03:35 20762 8192000000 10295173824 10737049600 1348334192 14.132 100 19 38 2 1400339760 46189888 5815680
15:04:58 21181 8192000000 10299910032 10748674048 1330007504 13.967 98 19 38 0 1382734800 47233392 5493904
15:06:20 34661 8192000000 10247414240 10689093632 1310885776 13.794 98 22 43 1 1328840320 12376544 5578000
15:08:28 36519 8192000000 10277058336 10716409856 1351482160 14.161 99 24 47 1 1364001744 7809312 4710272
15:10:06 78655 8192000000 10277957872 10722263040 1344878784 14.101 99 7 14 2 1355338656 9131824 1328048
15:12:26 119539 8192000000 10285410064 10718703616 1324699056 13.919 96 27 54 1 1341193312 10572480 5921776
15:15:36 96702 8192000000 10292182992 10735611904 1347761696 14.127 98 33 65 1 1365761392 9503392 8496304
15:18:17 45321 8192000000 10287608608 10748821504 1362347456 14.258 99 22 44 0 1384505024 16812416 5345152
15:20:04 23504 8192000000 10285014368 10737860608 1373688832 14.360 99 19 38 0 1389921616 11869488 4363296
15:21:28 22791 8192000000 10327036912 10766868480 1396688960 14.566 100 33 66 1 1419496448 14550192 8257296
15:22:59 30743 8192000000 10290265664 10759213056 1359547744 14.233 98 25 50 0 1388964064 22751760 6664560
15:24:42 78431 8192000000 10305375264 10760359936 1389711744 14.503 99 17 34 0 1405362976 11658480 3992752
15:27:01 44905 8192000000 10300895744 10768666624 1392725520 14.530 100 13 26 0 1413810224 17815904 3268800
15:28:48 75410 8192000000 10311849328 10774511616 1403174112 14.623 100 24 48 0 1419375840 11040176 5161552
15:31:04 9843 8192000000 10308873808 10773819392 1405250864 14.642 100 17 34 1 1418301008 8666464 4383680
15:32:15 48693 8192000000 10298207760 10759204864 1371642688 14.342 98 11 22 0 1380837936 6936864 2258384
15:34:22 58263 8192000000 10296310080 10761977856 1363350656 14.267 97 19 38 1 1380548832 12944720 4253456
15:36:34 66135 8192000000 10301166336 10758840320 1363540496 14.269 97 26 52 1 1380094304 10695744 5858064
15:39:04 53435 8192000000 10270271824 10723635200 1311260352 13.798 95 15 30 0 1323965232 9552496 3152384
15:41:05 21788 8192000000 10295991216 10738106368 1377011440 14.390 100 30 60 1 1393393136 9772672 6609024
15:42:27 46020 8192000000 10266897024 10708398080 1269555120 13.418 93 10 20 1 1286462768 14877760 2029888
15:44:56 31476 8192000000 10258386384 10694725632 1302245280 13.716 97 33 65 1 1324976704 15033328 7698096
15:46:30 76627 8192000000 10229437344 10674520064 1311731472 13.802 98 38 77 0 1332813888 12104128 8978288
15:48:48 67746 8192000000 10254179504 10708197376 1347282112 14.123 100 19 38 0 1360788880 9542832 3963936
15:50:56 51089 8192000000 10243914864 10694533120 1311422176 13.799 97 16 32 0 1332182240 16608592 4151472
15:52:47 56144 8192000000 10258868016 10719617024 1352280160 14.168 100 17 34 2 1374238448 17712304 4245984
15:54:50 73058 8192000000 10253472336 10706747392 1302764784 13.720 96 12 24 0 1322647360 16922944 2959632
15:57:26 88632 8192000000 10267761280 10712981504 1324522448 13.918 98 22 44 0 1342949360 12774688 5652224
15:59:55 30035 8192000000 10264172960 10706509824 1337592128 14.036 99 32 64 0 1352882752 8265200 7025424
16:01:26 31470 8192000000 10283934736 10733731840 1358319488 14.222 99 45 90 1 1382258352 14373536 9565328
16:02:59 108374 8192000000 10288633120 10748112896 1346771920 14.118 98 9 18 0 1365095008 16467872 1855216
16:06:11 49251 8192000000 10300235104 10743435264 1364873440 14.281 99 26 52 2 1385955584 14548576 6533568
16:08:02 54844 8192000000 10297662464 10762489856 1369664432 14.324 99 6 12 0 1383403104 12830192 908480
16:09:57 92919 8192000000 10306614224 10760642560 1395219712 14.552 100 27 54 1 1409621488 8708304 5693472
16:12:31 69623 8192000000 10282987344 10753110016 1370500336 14.332 98 11 22 1 1383856432 11207392 2148704
16:15:37 26766 8192000000 10297767600 10762354688 1387364384 14.482 100 20 40 1 1410520992 18713456 4443152
16:17:31 51004 8192000000 10309087472 10764566528 1385313392 14.464 99 17 34 0 1403480944 14107680 4059872
16:19:23 83363 8192000000 10314049632 10771861504 1375280720 14.374 98 20 40 0 1400999696 20673984 5044992
16:21:47 67823 8192000000 10300267952 10767736832 1374855728 14.371 98 15 31 0 1400906336 22312464 3738144
16:24:02 73700 8192000000 10312029120 10766376960 1379832336 14.415 99 10 20 0 1389868720 8179200 1857184
16:26:19 80358 8192000000 10285001056 10734084096 1298906800 13.685 93 9 18 0 1314501872 13763824 1831248
16:28:39 43717 8192000000 10241052128 10673963008 1272513984 13.445 94 26 52 0 1292210432 14197328 5499120
16:30:33 55953 8192000000 10228318704 10680274944 1296212368 13.661 97 13 26 0 1306390944 7119920 3058656
16:32:38 60192 8192000000 10252556000 10711130112 1345060176 14.103 99 14 28 1 1360667440 12344240 3263024
16:34:38 97730 8192000000 10270564224 10724323328 1343251232 14.087 99 11 21 1 1359751488 13627280 2872976
16:37:19 60741 8192000000 9883618144 10303262720 1004543056 10.923 74 9 18 0 1022205696 15562272 2100368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-05-2022 16:40:06 to 13-05-2022 16:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-05-2022 16:40:21 to 13-05-2022 16:40: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          244373
MON$OLDEST_ACTIVE               244374
MON$OLDEST_SNAPSHOT             244374
MON$NEXT_TRANSACTION            244374
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                       2042080
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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'

13.05.22 16:40: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
2022-05-13 16:40:27 No severe PSQL-related problems occured
13.05.22 16:40:28. End of report.