Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2022 01: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 15791404 kB
MemAvailable 30885612 kB
Buffers 0 kB
Cached 15891644 kB
SwapCached 2552 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.11
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-08-14 01:38:24
DTS_END                         2022-08-14 01:38:23.9980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55692
TRN_ID                          239042
ATT_ID                          682
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: 9648.03 20435 1157764
customer order: creation 174.23 354 20908
customer order: refuse 15.38 379 1845
order to supplier: creation 46.28 1207 5554
order to supplier: removal 3.69 95539 443
invoice (draft): creation 46.39 3458 5567
invoice (draft): removal 53.57 761 6428
invoice accept: apply 43.69 17415 5243
invoice accept: cancel 49.42 816 5930
customer reserve: creation 4030.88 172 483705
customer reserve: removal 4876.00 57 585120
realization accept: apply 84.32 185 10118
realization accept: cancel 90.58 108 10869
payment from customer: creation 60.18 105 7221
payment from customer: removal 5.51 84 661
payment to supplier: creation 51.07 123 6128
payment to supplier: removal 3.73 84 448
service: total inventory turnovers 5.09 1481 611
service: total monetary turnovers 7.12 48 854
service: aggregate perf. data 0.89 18066 107
service: refresh index statistics 0.03 268267 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2022 01:39:28 to 14-08-2022 01:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4611
TEST_TIME 2 3990
TEST_TIME 3 4512
TEST_TIME 4 5116
TEST_TIME 5 5174
TEST_TIME 6 5155
TEST_TIME 7 5159
TEST_TIME 8 5197
TEST_TIME 9 5943
TEST_TIME 10 5911
TEST_TIME 11 6464
TEST_TIME 12 6646
TEST_TIME 13 6956
TEST_TIME 14 7260
TEST_TIME 15 7353
TEST_TIME 16 7640
TEST_TIME 17 7978
TEST_TIME 18 7962
TEST_TIME 19 8002
TEST_TIME 20 8165
TEST_TIME 21 8373
TEST_TIME 22 8630
TEST_TIME 23 9224
TEST_TIME 24 8999
TEST_TIME 25 8939
TEST_TIME 26 9082
TEST_TIME 27 9249
TEST_TIME 28 9809
TEST_TIME 29 10190
TEST_TIME 30 10990
TEST_TIME 31 10887
TEST_TIME 32 10689
TEST_TIME 33 10749
TEST_TIME 34 10886
TEST_TIME 35 10771
TEST_TIME 36 10604
TEST_TIME 37 10580
TEST_TIME 38 10343
TEST_TIME 39 10369
TEST_TIME 40 10285
TEST_TIME 41 10411
TEST_TIME 42 10321
TEST_TIME 43 10164
TEST_TIME 44 10094
TEST_TIME 45 10055
TEST_TIME 46 10144
TEST_TIME 47 10085
TEST_TIME 48 10089
TEST_TIME 49 9971
TEST_TIME 50 9900
TEST_TIME 51 9869
TEST_TIME 52 9866
TEST_TIME 53 9774
TEST_TIME 54 9799
TEST_TIME 55 9684
TEST_TIME 56 9672
TEST_TIME 57 9536
TEST_TIME 58 9566
TEST_TIME 59 9567
TEST_TIME 60 9558
TEST_TIME 61 9614
TEST_TIME 62 9699
TEST_TIME 63 9684
TEST_TIME 64 9641
TEST_TIME 65 9839
TEST_TIME 66 9782
TEST_TIME 67 9822
TEST_TIME 68 9862
TEST_TIME 69 9865
TEST_TIME 70 9875
TEST_TIME 71 9887
TEST_TIME 72 10007
TEST_TIME 73 10038
TEST_TIME 74 9940
TEST_TIME 75 9884
TEST_TIME 76 9882
TEST_TIME 77 9897
TEST_TIME 78 9854
TEST_TIME 79 9807
TEST_TIME 80 9761
TEST_TIME 81 9773
TEST_TIME 82 9751
TEST_TIME 83 9733
TEST_TIME 84 9663
TEST_TIME 85 9589
TEST_TIME 86 9578
TEST_TIME 87 9602
TEST_TIME 88 9568
TEST_TIME 89 9538
TEST_TIME 90 9627
TEST_TIME 91 9589
TEST_TIME 92 9610
TEST_TIME 93 9654
TEST_TIME 94 9647
TEST_TIME 95 9637
TEST_TIME 96 9721
TEST_TIME 97 9721
TEST_TIME 98 9769
TEST_TIME 99 9747
TEST_TIME 100 9798
TEST_TIME 101 9789
TEST_TIME 102 9842
TEST_TIME 103 9833
TEST_TIME 104 9756
TEST_TIME 105 9732
TEST_TIME 106 9715
TEST_TIME 107 9679
TEST_TIME 108 9661
TEST_TIME 109 9637
TEST_TIME 110 9632
TEST_TIME 111 9626
TEST_TIME 112 9577
TEST_TIME 113 9537
TEST_TIME 114 9513
TEST_TIME 115 9530
TEST_TIME 116 9514
TEST_TIME 117 9529
TEST_TIME 118 9527
TEST_TIME 119 9652
TEST_TIME 120 9652

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-08-2022 01:39:31 to 14-08-2022 01:39:35.

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 1604114 1604114 0 0.00 0 21590 6 0 0
doc_list_biud 1604114 1604114 0 0.00 0 33754 26 0 0
sp_add_invoice_to_stock 5360 5243 117 2.18 29 165180 17415 0 117
sp_cancel_adding_invoice 5930 5930 0 0.00 5 52238 817 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 19281 380 0 0
sp_cancel_customer_reserve 585120 585120 0 0.00 0 22585 58 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 5078 85 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 3213 84 0 0
sp_cancel_supplier_invoice 6437 6428 9 0.13 1 17380 761 0 9
sp_cancel_supplier_order 443 443 0 0.00 7 1077028 95540 0 0
sp_cancel_write_off 10869 10869 0 0.00 1 14301 109 0 0
sp_client_order 20908 20908 0 0.00 3 15446 354 0 0
sp_customer_reserve 483705 483705 0 0.00 0 125495 172 0 0
sp_fill_shopping_cart 32009 32009 0 0.00 1 22175 41 0 0
sp_fill_shopping_cart_clo_res 483701 483701 0 0.00 0 13894 13 0 0
sp_get_clo_for_invoice 5236 5236 0 0.00 0 24825 279 0 0
sp_kill_cost_storno 17908 17908 0 0.00 0 14299 54 0 0
sp_kill_qstorno_ret_qs2qd 1187980 1187980 0 0.00 0 22582 31 0 0
sp_kill_qty_storno 625989 625989 0 0.00 0 33747 67 0 0
sp_lock_selected_doc 21352 21352 0 0.00 0 12513 7 0 0
sp_make_cost_storno 28703 28703 0 0.00 0 21588 53 0 0
sp_multiply_rows_for_pdistr 28703 28703 0 0.00 0 6689 27 0 0
sp_multiply_rows_for_qdistr 20908 20908 0 0.00 0 14831 155 0 0
sp_pay_from_customer 7221 7221 0 0.00 4 21634 105 0 0
sp_pay_to_supplier 6128 6128 0 0.00 4 21699 123 0 0
sp_payment_common 13349 13349 0 0.00 1 21689 26 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 19274 351 0 0
sp_qd_handle_on_invoice_upd_sts 11166 11166 0 0.00 0 33739 318 0 0
sp_qd_handle_on_reserve_upd_sts 10118 10118 0 0.00 0 21125 56 0 0
sp_reserve_write_off 10118 10118 0 0.00 6 21351 185 0 0
sp_supplier_invoice 5567 5567 0 0.00 6 110509 3458 0 0
sp_supplier_order 5554 5554 0 0.00 5 19505 1208 0 0
srv_aggregate_perf_data 107 107 0 0.00 2825 205160 18066 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1477 102320 39592 0 0
srv_find_qd_qs_mism 1107834 1107834 0 0.00 0 18521 5 0 0
srv_make_invnt_saldo 611 611 0 0.00 38 162929 1482 0 0
srv_make_money_saldo 854 854 0 0.00 1 13136 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 215143 358111 268267 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5243 5243 0 0.00 0 16297 178 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 7825 100 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 1368 8 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 5691 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 1200 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 3000 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 2203 20 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 26392 1525 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 2638 9 0 0
t$perf-norm:sp_client_order 20908 20908 0 0.00 0 5225 17 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 3770 10 0 0
t$perf-norm:sp_pay_from_customer 7221 7221 0 0.00 0 7122 13 0 0
t$perf-norm:sp_pay_to_supplier 6128 6128 0 0.00 0 5858 9 0 0
t$perf-norm:sp_reserve_write_off 10118 10118 0 0.00 0 7171 14 0 0
t$perf-norm:sp_supplier_invoice 5567 5567 0 0.00 0 5576 17 0 0
t$perf-norm:sp_supplier_order 5554 5554 0 0.00 0 4463 12 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 431 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 243 4 0 0
t$perf-norm:srv_make_invnt_saldo 611 611 0 0.00 0 5125 27 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 4094 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 6 0 0
v_add_invoice_to_stock 10706 10472 234 2.18 0 5401 21 0 234
v_all_customers 38612 38612 0 0.00 0 19 1 0 0
v_all_suppliers 31008 31008 0 0.00 0 88 1 0 0
v_all_wares 41816 41816 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 5856 12 0 0
v_cancel_client_order 3690 3690 0 0.00 0 2317 6 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 4963 9 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 964 3 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 1288 7 0 0
v_cancel_supplier_invoice 858 840 18 2.09 0 2975 22 0 18
v_cancel_supplier_order 886 886 0 0.00 0 2033 6 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4363 21 0 0
v_max_id_clo_ord 5554 5554 0 0.00 0 324 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 4004 20 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 6229 4 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 1046 5 0 0
v_max_non_paid_realizn 3629 3629 0 0.00 0 2365 4 0 0
v_min_id_clo_ord 5554 5554 0 0.00 0 4483 7 0 0
v_min_id_clo_res 993 993 0 0.00 0 1666 7 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 6436 12 0 0
v_min_non_paid_invoice 6128 6128 0 0.00 0 21679 23 0 0
v_min_non_paid_realizn 7221 7221 0 0.00 0 5156 14 0 0
v_our_firm 10388 10388 0 0.00 0 21 1 0 0
v_reserve_write_off 20236 20236 0 0.00 0 7256 7 0 0
x_lock_depdocs_on_canc_invoice 11938 11938 0 0.00 0 18477 119 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 18700 391 0 0
x_make_qstorno_1000_1200 5554 5554 0 0.00 1 19471 1128 0 0
x_make_qstorno_1200_2000 5567 5567 0 0.00 3 110491 3300 0 0
x_make_qstorno_2100_3300 483705 483705 0 0.00 0 125493 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2022 01:39:36 to 14-08-2022 01:39:40.

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
23:08:27 7 8192000000 8810255248 8825315328 14785872 0.180 4 4 5 0 15012608 87456 139280
23:09:29 32850 8192000000 9922474960 10235916288 1005221936 10.929 100 91 183 0 1034932944 5764400 23946608
23:11:12 5936 8192000000 9952059104 10271965184 1035072352 11.217 100 92 184 0 1067154384 8333424 23748608
23:12:26 12878 8192000000 9981142512 10312822784 1067621808 11.529 100 86 172 0 1098150016 9319664 21208544
23:13:44 10327 8192000000 10034308928 10377809920 1120245552 12.029 100 87 174 0 1151141984 10290224 20606208
23:15:00 3984 8192000000 10078922656 10437099520 1164043120 12.441 100 74 148 0 1192805664 11906816 16855728
23:16:06 3854 8192000000 10123842048 10490961920 1212431376 12.892 100 79 158 1 1243365968 13620768 17313824
23:17:13 2609 8192000000 10146987984 10522419200 1241995216 13.165 100 81 163 0 1273389168 13542160 17851792
23:18:19 1695 8192000000 10182646976 10562420736 1273077536 13.450 100 79 157 5 1303810320 13600144 17132640
23:19:22 1749 8192000000 10215017856 10601025536 1300495712 13.700 100 90 178 0 1332449424 12356048 19597664
23:20:26 1155 8192000000 10227177040 10616209408 1311929440 13.804 100 84 170 0 1344939200 13931664 19078096
23:21:29 10504 8192000000 10220138320 10621902848 1319777312 13.875 100 53 105 2 1337777632 6074880 11925440
23:22:43 3088 8192000000 10220942112 10632585216 1327193616 13.942 100 35 70 1 1343436896 7424672 8818608
23:23:48 11871 8192000000 10220992624 10638028800 1331885248 13.984 100 11 22 0 1339672416 5387888 2399280
23:25:02 47809 8192000000 10251822240 10657128448 1341797168 14.074 100 51 103 0 1359375680 5315616 12262896
23:26:51 4457 8192000000 10246833440 10665254912 1347086864 14.121 100 35 70 1 1365654928 10536896 8031168
23:27:57 7805 8192000000 10230810064 10651320320 1328984480 13.958 99 22 44 0 1340866240 5925536 5956224
23:29:05 45383 8192000000 10197283392 10614919168 1300412976 13.699 99 37 74 0 1315233296 6551888 8268432
23:30:57 6736 8192000000 10183504976 10585661440 1259096912 13.322 97 38 76 2 1274953536 7540752 8315872
23:32:05 5166 8192000000 10166510224 10569912320 1264584704 13.372 99 26 52 0 1276251552 5514992 6151856
23:33:12 70639 8192000000 10141484592 10543693824 1209202016 12.862 96 35 66 1 1222565168 4845360 8517792
23:35:23 10919 8192000000 10176240336 10574831616 1265230416 13.378 99 30 61 0 1278924640 6818752 6875472
23:36:35 5153 8192000000 10178569136 10585812992 1284705024 13.556 100 34 71 0 1298229376 5404272 8120080
23:37:41 6415 8192000000 10183164736 10595880960 1293528704 13.636 100 18 34 0 1303002592 5178080 4295808
23:38:51 35267 8192000000 10209675984 10621505536 1315098736 13.832 100 31 61 1 1326414608 4726592 6589280
23:40:39 12597 8192000000 10249034304 10659438592 1339189200 14.050 100 58 111 0 1364535328 12417904 12928224
23:41:57 11532 8192000000 10262036672 10671026176 1351960384 14.165 100 64 127 0 1381168256 14362400 14845472
23:43:13 16607 8192000000 10274578816 10692300800 1362847248 14.263 100 56 110 0 1389163680 12814816 13501616
23:44:39 29566 8192000000 10298529520 10724114432 1383147024 14.445 100 34 66 0 1411556384 20421184 7988176
23:46:15 12505 8192000000 10321153792 10739720192 1391268080 14.517 100 60 118 1 1432410576 26662608 14479888
23:47:31 7753 8192000000 10323318464 10744098816 1396597600 14.565 100 63 122 2 1437301856 26527664 14176592
23:48:41 6971 8192000000 10337291488 10756059136 1402223072 14.615 100 71 140 0 1448657664 29503232 16931360
23:49:59 2920 8192000000 10339809936 10760134656 1408307296 14.669 100 74 143 0 1455775680 29340944 18127440
23:51:18 2115 8192000000 10347617824 10762620928 1403191248 14.623 100 78 156 1 1454001760 31336528 19473984
23:52:26 13781 8192000000 10323744272 10751098880 1389031088 14.497 99 39 79 0 1424355728 25569216 9755424
23:53:46 8068 8192000000 10338159568 10758139904 1397799536 14.575 100 67 132 2 1444356560 30816272 15740752
23:55:00 1476 8192000000 10336439536 10741665792 1380618112 14.422 99 88 177 0 1436500032 34346224 21535696
23:56:08 9800 8192000000 10336552048 10748940288 1378271232 14.401 99 66 130 0 1428026896 34202352 15553312
23:57:22 1849 8192000000 10353277008 10759069696 1386138576 14.471 99 72 143 1 1447455312 43003440 18313296
23:58:30 2236 8192000000 10363738544 10768142336 1390794864 14.513 100 78 155 0 1460528240 49776048 19957328
23:59:39 10215 8192000000 10357701632 10778025984 1398715328 14.584 100 46 92 0 1464002976 52183680 13103968
00:00:55 6215 8192000000 10359392720 10781716480 1399477680 14.590 100 51 102 0 1463835184 50811392 13546112
00:02:04 13643 8192000000 10341801456 10766372864 1396737744 14.566 99 60 118 1 1452492368 39569536 16185088
00:03:48 15583 8192000000 10351148240 10788356096 1405554336 14.644 100 39 78 2 1466632704 50062976 11015392
00:05:12 15744 8192000000 10340708832 10781077504 1380482048 14.421 99 29 58 0 1440057552 51193520 8381984
00:07:06 35999 8192000000 10322584192 10778144768 1366234096 14.293 100 15 30 0 1425715328 54096176 5385056
00:09:18 68455 8192000000 10259374208 10687135744 1297609520 13.674 94 16 31 1 1311584640 10891920 3083200
00:11:29 25572 8192000000 10282472128 10723840000 1358853504 14.227 99 16 32 0 1375697616 13332880 3511232
00:13:02 75525 8192000000 10301701680 10736599040 1337720944 14.037 97 12 24 0 1353899472 13312416 2866112
00:15:18 102320 8192000000 10292171536 10732507136 1358387856 14.223 99 30 61 1 1378969888 13326320 7255712
00:18:04 16976 8192000000 10311445680 10743508992 1372546336 14.350 99 66 130 1 1398901680 12176768 14178576
00:19:25 67996 8192000000 10284289296 10743193600 1344806688 14.101 98 15 28 0 1364417600 16591104 3019808
00:21:35 89065 8192000000 10289174448 10742390784 1366300480 14.294 99 18 36 0 1383864704 13076800 4487424
00:24:05 20229 8192000000 10305643024 10752000000 1384604608 14.458 100 33 63 1 1404493408 11696528 8192272
00:25:29 32840 8192000000 10292442048 10738630656 1366372320 14.295 99 15 29 0 1381026512 11585104 3069088
00:27:03 25215 8192000000 10275639712 10726060032 1361479696 14.251 99 14 25 0 1375231296 11244640 2506960
00:28:30 53364 8192000000 10276004704 10735198208 1360005296 14.237 99 14 28 0 1376359376 13057440 3296640
00:30:24 60542 8192000000 10273322144 10742480896 1378937904 14.407 100 11 22 0 1393526416 11851584 2736928
00:32:29 55625 8192000000 10287661024 10746716160 1385785568 14.468 100 16 32 0 1396728864 7750864 3192432
00:34:29 55175 8192000000 10278669488 10747080704 1385926432 14.470 100 16 32 2 1400643872 11482496 3234944
00:36:31 45975 8192000000 10293828288 10761494528 1394099808 14.542 100 5 10 0 1405620000 10354896 1165296
00:38:18 56876 8192000000 10294021744 10752991232 1365326112 14.285 98 24 46 0 1381023408 10572624 5124672
00:40:16 42072 8192000000 10277003200 10743468032 1347536208 14.125 97 11 20 1 1360983824 11606112 1841504
00:42:03 40690 8192000000 10299275824 10751500288 1356931744 14.210 97 21 43 0 1376454912 14903024 4620144
00:43:53 28579 8192000000 10290310880 10734751744 1337212720 14.032 97 19 39 0 1349432736 7828224 4391792
00:45:25 65625 8192000000 10274288144 10731696128 1326431648 13.935 97 18 36 0 1351508912 21058368 4018896
00:47:35 9910 8192000000 10283403152 10719457280 1364629056 14.279 100 37 74 0 1381854704 8634080 8591568
00:48:47 92173 8192000000 10294826832 10740129792 1319508992 13.872 96 14 28 0 1341731904 19001504 3221408
00:51:21 51907 8192000000 10270371072 10722476032 1328577472 13.954 97 12 24 0 1341742768 10687856 2477440
00:53:15 58355 8192000000 10266801120 10715291648 1330562400 13.972 97 32 64 1 1345404896 6981344 7861152
00:55:18 78700 8192000000 10235558496 10680422400 1303086672 13.723 97 15 30 0 1321388176 14461280 3840224
00:58:00 43146 8192000000 10265573248 10712723456 1358001504 14.219 100 17 34 0 1373155456 10756016 4397936
00:59:50 44101 8192000000 10265518128 10715848704 1343578912 14.090 98 25 46 1 1356107040 7794176 4733952
01:01:36 48545 8192000000 10233775456 10681155584 1297075088 13.669 96 16 33 0 1310997392 10267328 3654976
01:03:25 43051 8192000000 10249793168 10702675968 1348506816 14.134 100 17 34 0 1364362144 11859200 3996128
01:05:11 49896 8192000000 10251773872 10722193408 1355322272 14.195 100 9 18 0 1370145568 12931008 1892288
01:07:10 41686 8192000000 10276141856 10731216896 1355057792 14.193 99 17 32 0 1367403216 8905344 3440080
01:08:57 79890 8192000000 10289379392 10742480896 1369878624 14.326 99 14 28 0 1383249040 10559504 2810912
01:11:17 33078 8192000000 10299318992 10754359296 1377913280 14.398 99 30 60 0 1396526464 12272368 6340816
01:12:51 59595 8192000000 10294353664 10742591488 1355925040 14.201 98 25 50 0 1377827344 16485712 5416592
01:14:52 58324 8192000000 10290641040 10730938368 1326087696 13.932 96 27 54 0 1346060912 13915424 6057792
01:16:53 44524 8192000000 10315862896 10752057344 1382426528 14.438 100 29 57 0 1400085600 11275168 6383904
01:18:43 91044 8192000000 10302604880 10767167488 1376536880 14.386 99 11 21 0 1396198880 17466112 2195888
01:21:16 65959 8192000000 10284485728 10748530688 1371318464 14.339 99 18 35 0 1389617776 14341552 3957760
01:23:22 53585 8192000000 10297949648 10748600320 1371507712 14.341 99 22 44 0 1384553536 8000432 5045392
01:25:32 30980 8192000000 10297905616 10750881792 1393542400 14.537 100 15 30 0 1405761584 8759872 3459312
01:27:04 61109 8192000000 10289475328 10760888320 1369012128 14.318 98 11 22 1 1381906832 10288576 2606128
01:30:16 44824 8192000000 10268959888 10737577984 1379070944 14.408 100 9 18 0 1388295520 7083072 2141504
01:32:06 51219 8192000000 10280841136 10734968832 1337074016 14.031 98 16 30 0 1353029264 12266720 3688528
01:34:01 39312 8192000000 10288713424 10739773440 1359083152 14.229 99 35 71 0 1384129872 17323296 7723424
01:35:45 63774 8192000000 10278556064 10726506496 1322733312 13.901 96 21 42 0 1342801296 14728224 5339760
01:37:50 52222 8192000000 9201732752 9477943296 340752576 3.993 26 1 2 0 351686640 10891712 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 14-08-2022 01:39:42 to 14-08-2022 01:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-08-2022 01:39:58 to 14-08-2022 01:40:00.

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          239348
MON$OLDEST_ACTIVE               239349
MON$OLDEST_SNAPSHOT             239349
MON$NEXT_TRANSACTION            239349
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                       2036064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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'

14.08.22 01:40:01. 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-08-14 01:40:03 No severe PSQL-related problems occured
14.08.22 01:40:04. End of report.