Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.03.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 17054284 kB
MemAvailable 31623640 kB
Buffers 0 kB
Cached 14658900 kB
SwapCached 2140 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-03-26 16:38:13
DTS_END                         2022-03-26 16:38:13.0160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57160
TRN_ID                          236886
ATT_ID                          656
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: 9021.19 22320 1082543
customer order: creation 172.26 381 20671
customer order: refuse 14.05 360 1686
order to supplier: creation 45.28 1237 5434
order to supplier: removal 3.46 90749 415
invoice (draft): creation 45.78 3141 5493
invoice (draft): removal 51.36 742 6163
invoice accept: apply 40.79 18684 4895
invoice accept: cancel 46.25 785 5550
customer reserve: creation 3790.03 184 454804
customer reserve: removal 4517.75 54 542130
realization accept: apply 82.00 177 9840
realization accept: cancel 83.05 104 9966
payment from customer: creation 59.65 124 7158
payment from customer: removal 5.68 82 682
payment to supplier: creation 48.34 123 5801
payment to supplier: removal 3.72 67 446
service: total inventory turnovers 4.93 1140 591
service: total monetary turnovers 5.93 28 712
service: aggregate perf. data 0.85 22075 102
service: refresh index statistics 0.03 306158 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-03-2022 16:39:34 to 26-03-2022 16:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6537
TEST_TIME 2 7251
TEST_TIME 3 6697
TEST_TIME 4 6303
TEST_TIME 5 5929
TEST_TIME 6 5945
TEST_TIME 7 6515
TEST_TIME 8 6198
TEST_TIME 9 6098
TEST_TIME 10 6165
TEST_TIME 11 6368
TEST_TIME 12 6772
TEST_TIME 13 6837
TEST_TIME 14 6852
TEST_TIME 15 6856
TEST_TIME 16 7440
TEST_TIME 17 8114
TEST_TIME 18 8210
TEST_TIME 19 8543
TEST_TIME 20 9330
TEST_TIME 21 9220
TEST_TIME 22 9517
TEST_TIME 23 10243
TEST_TIME 24 10537
TEST_TIME 25 10530
TEST_TIME 26 10306
TEST_TIME 27 10077
TEST_TIME 28 10135
TEST_TIME 29 10289
TEST_TIME 30 10107
TEST_TIME 31 10007
TEST_TIME 32 9950
TEST_TIME 33 10085
TEST_TIME 34 9963
TEST_TIME 35 10028
TEST_TIME 36 9913
TEST_TIME 37 9797
TEST_TIME 38 9789
TEST_TIME 39 9652
TEST_TIME 40 9562
TEST_TIME 41 9502
TEST_TIME 42 9484
TEST_TIME 43 9474
TEST_TIME 44 9568
TEST_TIME 45 9440
TEST_TIME 46 9431
TEST_TIME 47 9378
TEST_TIME 48 9292
TEST_TIME 49 9251
TEST_TIME 50 9155
TEST_TIME 51 9102
TEST_TIME 52 9006
TEST_TIME 53 8955
TEST_TIME 54 8990
TEST_TIME 55 8935
TEST_TIME 56 8989
TEST_TIME 57 9001
TEST_TIME 58 9029
TEST_TIME 59 9045
TEST_TIME 60 8999
TEST_TIME 61 9168
TEST_TIME 62 9253
TEST_TIME 63 9281
TEST_TIME 64 9428
TEST_TIME 65 9407
TEST_TIME 66 9398
TEST_TIME 67 9386
TEST_TIME 68 9291
TEST_TIME 69 9335
TEST_TIME 70 9265
TEST_TIME 71 9287
TEST_TIME 72 9255
TEST_TIME 73 9179
TEST_TIME 74 9111
TEST_TIME 75 9043
TEST_TIME 76 9077
TEST_TIME 77 9054
TEST_TIME 78 9073
TEST_TIME 79 9051
TEST_TIME 80 9088
TEST_TIME 81 9110
TEST_TIME 82 9144
TEST_TIME 83 9185
TEST_TIME 84 9155
TEST_TIME 85 9133
TEST_TIME 86 9195
TEST_TIME 87 9268
TEST_TIME 88 9302
TEST_TIME 89 9254
TEST_TIME 90 9182
TEST_TIME 91 9143
TEST_TIME 92 9164
TEST_TIME 93 9105
TEST_TIME 94 9074
TEST_TIME 95 9030
TEST_TIME 96 9036
TEST_TIME 97 9088
TEST_TIME 98 9098
TEST_TIME 99 9081
TEST_TIME 100 9058
TEST_TIME 101 9046
TEST_TIME 102 9121
TEST_TIME 103 9111
TEST_TIME 104 9124
TEST_TIME 105 9178
TEST_TIME 106 9180
TEST_TIME 107 9147
TEST_TIME 108 9259
TEST_TIME 109 9236
TEST_TIME 110 9178
TEST_TIME 111 9133
TEST_TIME 112 9170
TEST_TIME 113 9126
TEST_TIME 114 9091
TEST_TIME 115 9048
TEST_TIME 116 9032
TEST_TIME 117 9008
TEST_TIME 118 9059
TEST_TIME 119 9095
TEST_TIME 120 9027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-03-2022 16:39:37 to 26-03-2022 16:39:41.

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 1503269 1503269 0 0.00 0 44847 6 0 0
doc_list_biud 1503269 1503269 0 0.00 0 57154 25 0 0
sp_add_invoice_to_stock 5011 4895 116 2.31 36 225522 18685 0 116
sp_cancel_adding_invoice 5550 5550 0 0.00 3 64854 785 0 0
sp_cancel_client_order 1686 1686 0 0.00 4 13944 360 0 0
sp_cancel_customer_reserve 542130 542130 0 0.00 1 57155 54 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 5145 82 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6005 68 0 0
sp_cancel_supplier_invoice 6170 6163 7 0.11 1 14600 743 0 7
sp_cancel_supplier_order 415 415 0 0.00 5 1075002 90749 0 0
sp_cancel_write_off 9966 9966 0 0.00 1 8515 104 0 0
sp_client_order 20671 20671 0 0.00 3 19461 381 0 0
sp_customer_reserve 454804 454804 0 0.00 0 79754 185 0 0
sp_fill_shopping_cart 31591 31591 0 0.00 1 15455 37 0 0
sp_fill_shopping_cart_clo_res 454792 454792 0 0.00 0 19809 16 0 0
sp_get_clo_for_invoice 4882 4882 0 0.00 0 56546 385 0 0
sp_kill_cost_storno 16644 16644 0 0.00 0 18938 53 0 0
sp_kill_qstorno_ret_qs2qd 1100804 1100804 0 0.00 0 57151 30 0 0
sp_kill_qty_storno 580632 580632 0 0.00 0 57153 64 0 0
sp_lock_selected_doc 20481 20481 0 0.00 0 7497 5 0 0
sp_make_cost_storno 27681 27681 0 0.00 0 44834 54 0 0
sp_multiply_rows_for_pdistr 27681 27681 0 0.00 0 9868 28 0 0
sp_multiply_rows_for_qdistr 20671 20671 0 0.00 0 12784 182 0 0
sp_pay_from_customer 7158 7158 0 0.00 4 10979 124 0 0
sp_pay_to_supplier 5801 5801 0 0.00 5 12281 124 0 0
sp_payment_common 12959 12959 0 0.00 1 7122 25 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 13938 332 0 0
sp_qd_handle_on_invoice_upd_sts 10432 10432 0 0.00 0 32813 320 0 0
sp_qd_handle_on_reserve_upd_sts 9840 9840 0 0.00 0 9443 59 0 0
sp_reserve_write_off 9840 9840 0 0.00 6 17119 178 0 0
sp_supplier_invoice 5493 5493 0 0.00 6 82579 3141 0 0
sp_supplier_order 5434 5434 0 0.00 5 27887 1237 0 0
srv_aggregate_perf_data 102 102 0 0.00 2757 301305 22075 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2140 124428 46300 0 0
srv_find_qd_qs_mism 1034913 1034913 0 0.00 0 14628 6 0 0
srv_make_invnt_saldo 591 591 0 0.00 40 72689 1140 0 0
srv_make_money_saldo 712 712 0 0.00 1 4649 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 128653 432779 306159 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4895 4895 0 0.00 0 10978 160 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 4473 90 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 6608 23 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 6683 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 9929 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 351 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 4662 26 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 17580 1219 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 904 4 0 0
t$perf-norm:sp_client_order 20671 20671 0 0.00 0 6627 16 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 3026 7 0 0
t$perf-norm:sp_pay_from_customer 7158 7158 0 0.00 0 9817 12 0 0
t$perf-norm:sp_pay_to_supplier 5801 5801 0 0.00 0 8625 9 0 0
t$perf-norm:sp_reserve_write_off 9840 9840 0 0.00 0 10612 13 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 9786 16 0 0
t$perf-norm:sp_supplier_order 5434 5434 0 0.00 0 7915 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 840 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 725 14 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 2443 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9988 9756 232 2.32 0 4861 17 0 232
v_all_customers 37904 37904 0 0.00 0 92 0 0 0
v_all_suppliers 30276 30276 0 0.00 0 33 1 0 0
v_all_wares 41342 41342 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1350 7 0 0
v_cancel_client_order 3372 3372 0 0.00 0 7428 7 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 955 6 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 267 3 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 951 5 0 0
v_cancel_supplier_invoice 846 832 14 1.65 0 12307 35 0 14
v_cancel_supplier_order 830 830 0 0.00 0 767 5 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2781 12 0 0
v_max_id_clo_ord 5432 5432 0 0.00 0 2192 2 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 4662 23 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 5788 6 0 0
v_max_non_paid_invoice 1581 1581 0 0.00 0 1293 3 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 6127 5 0 0
v_min_id_clo_ord 5432 5432 0 0.00 0 4632 9 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 1811 6 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 5351 6 0 0
v_min_non_paid_invoice 5801 5801 0 0.00 0 5237 15 0 0
v_min_non_paid_realizn 7158 7158 0 0.00 0 7115 20 0 0
v_our_firm 10468 10468 0 0.00 0 31 1 0 0
v_reserve_write_off 19680 19680 0 0.00 0 3929 6 0 0
x_lock_depdocs_on_canc_invoice 11297 11297 0 0.00 0 13318 117 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 18055 374 0 0
x_make_qstorno_1000_1200 5434 5434 0 0.00 2 27874 1161 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 2 82571 3011 0 0
x_make_qstorno_2100_3300 454804 454804 0 0.00 0 79752 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-03-2022 16:39:42 to 26-03-2022 16:39:46.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:17 23843 8192000000 9891033696 10200977408 974802384 10.634 100 85 170 0 1003885760 6080496 23002880
14:10:45 14335 8192000000 9947445056 10267729920 1025934272 11.129 100 91 182 0 1057473872 8116480 23423120
14:12:06 20168 8192000000 9979106736 10313904128 1065889024 11.513 100 79 158 1 1094943792 9570464 19484304
14:13:30 12064 8192000000 10035785536 10383183872 1117766080 12.006 100 80 160 0 1148348384 11906432 18675872
14:14:48 11911 8192000000 10096647616 10457878528 1179379152 12.584 100 84 168 0 1211665760 13274192 19012416
14:16:02 4956 8192000000 10127058400 10493689856 1212012944 12.888 100 85 170 1 1245163440 14240752 18909744
14:17:09 4322 8192000000 10155919344 10529669120 1241090832 13.156 100 84 169 0 1273146176 13608032 18447312
14:18:16 2411 8192000000 10196824448 10577367040 1281531424 13.527 100 91 183 0 1313893472 12200016 20162032
14:19:19 2282 8192000000 10215516368 10595532800 1294959728 13.649 100 87 174 3 1326417648 12030768 19427152
14:20:22 6559 8192000000 10187322656 10596061184 1299690576 13.692 100 23 46 0 1311076432 6591184 4794672
14:21:29 6891 8192000000 10200617920 10614104064 1312461152 13.808 100 31 62 1 1326566896 7316608 6789136
14:22:38 10386 8192000000 10203115712 10619641856 1319379456 13.871 100 18 34 1 1328895712 5635904 3880352
14:23:49 57946 8192000000 10233648736 10639155200 1324847168 13.921 100 42 85 0 1343661744 9119920 9694656
14:25:47 8343 8192000000 10224704784 10639831040 1329940080 13.967 100 40 81 2 1346438240 7277824 9220336
14:26:56 9329 8192000000 10214231920 10631811072 1314499120 13.827 99 10 20 0 1322275600 5008896 2767584
14:28:07 15374 8192000000 10207810304 10631516160 1312270512 13.807 99 21 42 1 1322938816 5658672 5009632
14:29:29 5670 8192000000 10183257648 10589696000 1277240624 13.488 99 20 40 0 1286983360 5163312 4579424
14:30:36 8219 8192000000 10175453312 10580279296 1269279216 13.415 99 20 41 1 1279662336 5933152 4449968
14:31:44 58378 8192000000 10133261072 10539528192 1218873792 12.951 97 25 47 1 1228892640 4495888 5522960
14:33:44 7410 8192000000 10148885952 10554322944 1252464176 13.261 99 20 40 1 1262692560 5689248 4539136
14:34:55 11429 8192000000 10179042288 10584342528 1283058608 13.541 100 25 48 1 1295138704 5756000 6324096
14:36:07 63365 8192000000 10191261072 10602491904 1296066256 13.659 100 31 62 0 1309876944 5303168 8507520
14:38:11 8398 8192000000 10214215696 10623639552 1317042608 13.850 100 30 60 1 1329632320 5975680 6614032
14:39:30 19109 8192000000 10255212912 10666397696 1350421072 14.151 100 57 114 1 1373144624 10100624 12622928
14:41:02 15354 8192000000 10275775168 10692804608 1367777632 14.307 100 44 88 0 1389948160 10966224 11204304
14:42:30 36954 8192000000 10286036512 10710732800 1366962944 14.300 99 45 90 2 1392161200 14426736 10771520
14:44:12 13059 8192000000 10306449984 10730496000 1390090784 14.507 100 36 72 0 1416324912 17710784 8523344
14:45:29 37880 8192000000 10311048544 10742005760 1396786800 14.566 100 33 66 0 1427942400 23137120 8018480
14:47:11 10434 8192000000 10337321120 10757898240 1410738496 14.691 100 59 118 1 1446083648 21891056 13454096
14:48:26 4885 8192000000 10345910720 10764210176 1416413376 14.741 100 62 122 2 1455108464 23967296 14727792
14:49:35 2138 8192000000 10348902064 10767306752 1412688736 14.708 100 74 148 2 1461112256 30234800 18188720
14:50:43 9068 8192000000 10348995296 10768584704 1407201440 14.659 99 64 128 2 1451650880 27825792 16623648
14:52:07 2248 8192000000 10365171792 10775351296 1414793984 14.727 100 93 182 2 1468239888 31634496 21811408
14:53:17 3348 8192000000 10378502688 10799775744 1430746592 14.868 100 69 138 2 1485812368 37078368 17987408
14:54:24 2607 8192000000 10368946048 10780372992 1405860912 14.647 100 89 181 1 1473415824 44160864 23394048
14:55:28 12856 8192000000 10338599600 10768736256 1394683952 14.548 99 40 80 0 1443382304 37674640 11023712
14:56:46 11285 8192000000 10342418496 10778357760 1397602656 14.574 100 38 76 1 1451979280 43591744 10784880
14:58:00 25375 8192000000 10324955792 10765578240 1399850048 14.594 100 26 51 1 1441110576 34255952 7004576
14:59:28 13649 8192000000 10300992496 10734616576 1359548912 14.233 98 40 78 2 1395728384 25911984 10267488
15:00:57 27762 8192000000 10283994528 10734780416 1338912768 14.048 98 25 50 2 1376700800 30618160 7169872
15:02:29 44334 8192000000 10251185728 10678095872 1314411808 13.826 97 41 80 3 1334254256 9132416 10710032
15:04:15 44140 8192000000 10259368976 10689060864 1304437488 13.736 96 22 44 0 1320588928 10654496 5496944
15:06:25 49563 8192000000 10266763968 10699771904 1328372752 13.952 98 27 54 0 1356088448 20895072 6820624
15:08:48 77331 8192000000 10265861184 10711093248 1350491920 14.152 99 20 39 1 1376761088 21536256 4732912
15:11:25 90977 8192000000 10262352960 10702393344 1313588560 13.819 96 22 44 1 1331475312 12974176 4912576
15:13:56 22138 8192000000 10250148816 10681044992 1322292256 13.897 97 19 38 1 1339015936 12620992 4102688
15:15:21 21505 8192000000 10266829968 10701959168 1348378576 14.133 99 47 92 0 1370949920 11313264 11258080
15:16:44 11031 8192000000 10269655328 10714415104 1365630016 14.288 100 35 71 0 1386899648 12398576 8871056
15:17:58 124419 8192000000 10297997104 10749669376 1371881360 14.344 99 21 42 0 1401018112 24049968 5086784
15:21:04 63650 8192000000 10281877552 10731941888 1373626752 14.360 100 20 40 3 1400030400 21758720 4644928
15:23:13 9556 8192000000 10292923904 10744852480 1388080912 14.489 100 22 44 3 1402150672 9031696 5038064
15:24:25 67569 8192000000 10280415216 10755657728 1389961760 14.506 100 9 18 1 1403904960 11747040 2196160
15:26:53 84985 8192000000 10274433872 10739478528 1356153648 14.203 97 12 24 0 1366806496 7966160 2686688
15:29:23 83969 8192000000 10283861056 10750443520 1394630608 14.547 100 14 28 0 1405722656 7901808 3190240
15:32:01 66479 8192000000 10287873296 10753294336 1370404880 14.331 98 4 8 0 1380766160 9146944 1214336
15:34:08 39663 8192000000 10280820128 10733084672 1332423056 13.989 96 28 56 0 1352388096 14104832 5860208
15:35:55 41460 8192000000 10286917024 10746470400 1385493648 14.466 100 31 62 0 1405968832 13327296 7147888
15:37:37 79068 8192000000 10315928464 10767679488 1405921840 14.648 100 21 42 0 1428987616 18283264 4782512
15:39:56 60622 8192000000 10294881088 10753290240 1340237264 14.060 96 15 30 0 1369852880 26316560 3299056
15:41:57 82409 8192000000 10271366992 10725605376 1308900256 13.776 94 11 22 1 1323462096 12227568 2334272
15:44:20 18942 8192000000 10272706336 10702864384 1347764320 14.127 99 32 64 0 1364191056 9615248 6811488
15:45:42 97848 8192000000 10284240480 10730565632 1372528800 14.350 100 27 54 2 1396196880 17536640 6131440
15:48:44 8558 8192000000 10296743648 10734063616 1382186560 14.436 100 45 91 1 1402978800 10225856 10566384
15:49:53 51086 8192000000 10261353712 10717167616 1358150032 14.221 98 9 18 0 1367994368 7988416 1855920
15:51:52 20647 8192000000 10280796672 10736013312 1384777152 14.459 100 22 44 1 1394006720 4763056 4466512
15:53:13 64361 8192000000 10279179952 10742550528 1373956224 14.362 99 22 45 0 1387009008 8077232 4975552
15:55:18 66272 8192000000 10258008400 10713059328 1300273824 13.698 94 9 18 0 1312664240 10405904 1984512
15:57:26 49557 8192000000 10267544944 10708840448 1290394960 13.608 94 22 44 1 1304690208 9223008 5072240
15:59:52 82618 8192000000 10273134048 10713137152 1324595328 13.918 97 20 40 0 1344106288 14691376 4819584
16:02:17 37302 8192000000 10278625984 10715488256 1350976448 14.156 99 24 48 0 1367899904 11889840 5033616
16:03:54 87860 8192000000 10274756672 10738393088 1348040224 14.130 98 22 44 0 1368055648 15418944 4596480
16:07:03 30566 8192000000 10306988880 10738380800 1363640976 14.270 99 50 100 2 1384614144 9523744 11449424
16:08:34 81412 8192000000 10273927216 10711203840 1324402304 13.917 97 31 62 1 1344568800 13208256 6958240
16:11:00 86881 8192000000 10264915760 10716041216 1347218288 14.122 98 12 24 0 1355437536 5969504 2249744
16:13:27 53272 8192000000 10288827952 10744782848 1383525200 14.448 100 10 20 0 1394946944 8688336 2733408
16:15:26 104581 8192000000 10279877168 10734063616 1334903376 14.011 96 11 22 0 1347220320 9860192 2456752
16:18:15 19495 8192000000 10293728544 10735349760 1362742432 14.262 99 34 68 2 1380292176 9778336 7771408
16:19:41 64380 8192000000 10266919648 10734088192 1331915760 13.984 97 17 34 0 1350908240 15374224 3618256
16:21:47 22278 8192000000 10295363648 10734723072 1377825648 14.397 100 30 59 0 1400331232 16010848 6494736
16:23:23 80180 8192000000 10287212352 10736541696 1341844800 14.074 97 12 24 0 1356861712 12410944 2605968
16:25:49 32514 8192000000 10307352976 10753261568 1384049328 14.453 100 30 60 0 1407038512 16317728 6671456
16:27:22 110832 8192000000 10284837344 10724175872 1325044848 13.922 95 30 58 0 1339380848 7340496 6995504
16:30:24 70305 8192000000 10295895056 10752786432 1386533376 14.475 100 15 30 1 1407516672 17809120 3174176
16:32:35 59639 8192000000 10264886160 10718928896 1324076832 13.914 95 14 28 0 1338506736 10736368 3693536
16:34:49 59699 8192000000 10281203760 10735239168 1362229968 14.257 99 13 27 0 1379657168 14370256 3056944
16:36:56 80764 8192000000 9144810992 9392726016 313630048 3.687 23 7 12 2 323715600 8699536 1386016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-03-2022 16:39:48 to 26-03-2022 16:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          237186
MON$OLDEST_ACTIVE               237187
MON$OLDEST_SNAPSHOT             237187
MON$NEXT_TRANSACTION            237187
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                       1994240
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:474: 
:475: mscs-test-161.budzdorov.ru    Sat Mar 26 16:38:18 2022
:476:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:477: 
:478: 
:479: mscs-test-161.budzdorov.ru    Sat Mar 26 16:38:18 2022
:480:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:481: 
:482: 
:483: mscs-test-161.budzdorov.ru    Sat Mar 26 16:38:19 2022
:484:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:485: 
--- end of diff output ---

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

26.03.22 16:40:06. 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-03-26 16:40:09 No severe PSQL-related problems occured
26.03.22 16:40:10. End of report.