Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2021 16:44
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16378004 kB
MemAvailable 30848148 kB
Buffers 0 kB
Cached 15355516 kB
SwapCached 2476 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.8
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 2021-11-03 16:43:05
DTS_END                         2021-11-03 16:43:05.6040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42614
TRN_ID                          226016
ATT_ID                          675
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: 8682.64 26343 1041917
customer order: creation 160.44 351 19253
customer order: refuse 13.95 371 1674
order to supplier: creation 42.79 1212 5135
order to supplier: removal 3.30 112816 396
invoice (draft): creation 43.43 3589 5212
invoice (draft): removal 48.65 860 5838
invoice accept: apply 39.13 20585 4696
invoice accept: cancel 44.16 802 5299
customer reserve: creation 3676.33 200 441159
customer reserve: removal 4335.38 68 520245
realization accept: apply 78.63 181 9436
realization accept: cancel 73.72 143 8847
payment from customer: creation 55.57 119 6668
payment from customer: removal 5.50 101 660
payment to supplier: creation 46.67 132 5600
payment to supplier: removal 3.08 108 370
service: total inventory turnovers 4.73 1102 568
service: total monetary turnovers 6.37 28 764
service: aggregate perf. data 0.78 19821 93
service: refresh index statistics 0.03 364270 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2021 16:45:03 to 03-11-2021 16:45:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4015
TEST_TIME 2 3209
TEST_TIME 3 3712
TEST_TIME 4 3290
TEST_TIME 5 3646
TEST_TIME 6 3883
TEST_TIME 7 4487
TEST_TIME 8 4569
TEST_TIME 9 4821
TEST_TIME 10 5173
TEST_TIME 11 5429
TEST_TIME 12 5389
TEST_TIME 13 5763
TEST_TIME 14 6208
TEST_TIME 15 6283
TEST_TIME 16 6196
TEST_TIME 17 6566
TEST_TIME 18 6612
TEST_TIME 19 6762
TEST_TIME 20 6800
TEST_TIME 21 7169
TEST_TIME 22 7055
TEST_TIME 23 8135
TEST_TIME 24 8381
TEST_TIME 25 8592
TEST_TIME 26 8743
TEST_TIME 27 9028
TEST_TIME 28 9505
TEST_TIME 29 10391
TEST_TIME 30 10442
TEST_TIME 31 10292
TEST_TIME 32 10150
TEST_TIME 33 10008
TEST_TIME 34 9795
TEST_TIME 35 9724
TEST_TIME 36 9507
TEST_TIME 37 9712
TEST_TIME 38 9578
TEST_TIME 39 9471
TEST_TIME 40 9334
TEST_TIME 41 9308
TEST_TIME 42 9302
TEST_TIME 43 9228
TEST_TIME 44 9084
TEST_TIME 45 9014
TEST_TIME 46 9059
TEST_TIME 47 9038
TEST_TIME 48 9118
TEST_TIME 49 9141
TEST_TIME 50 9025
TEST_TIME 51 9025
TEST_TIME 52 8887
TEST_TIME 53 8785
TEST_TIME 54 8735
TEST_TIME 55 8695
TEST_TIME 56 8745
TEST_TIME 57 8756
TEST_TIME 58 8787
TEST_TIME 59 8745
TEST_TIME 60 8859
TEST_TIME 61 8988
TEST_TIME 62 9004
TEST_TIME 63 9003
TEST_TIME 64 8981
TEST_TIME 65 9062
TEST_TIME 66 9063
TEST_TIME 67 9157
TEST_TIME 68 9119
TEST_TIME 69 9148
TEST_TIME 70 9191
TEST_TIME 71 9150
TEST_TIME 72 9066
TEST_TIME 73 8985
TEST_TIME 74 8932
TEST_TIME 75 8995
TEST_TIME 76 8974
TEST_TIME 77 8918
TEST_TIME 78 8886
TEST_TIME 79 8866
TEST_TIME 80 8900
TEST_TIME 81 8847
TEST_TIME 82 8869
TEST_TIME 83 8891
TEST_TIME 84 8868
TEST_TIME 85 8884
TEST_TIME 86 8917
TEST_TIME 87 8897
TEST_TIME 88 8906
TEST_TIME 89 8964
TEST_TIME 90 8917
TEST_TIME 91 9017
TEST_TIME 92 9013
TEST_TIME 93 9026
TEST_TIME 94 8970
TEST_TIME 95 8915
TEST_TIME 96 8909
TEST_TIME 97 8902
TEST_TIME 98 8857
TEST_TIME 99 8832
TEST_TIME 100 8801
TEST_TIME 101 8798
TEST_TIME 102 8799
TEST_TIME 103 8826
TEST_TIME 104 8888
TEST_TIME 105 8880
TEST_TIME 106 8895
TEST_TIME 107 8878
TEST_TIME 108 8884
TEST_TIME 109 8858
TEST_TIME 110 8904
TEST_TIME 111 8913
TEST_TIME 112 8934
TEST_TIME 113 8929
TEST_TIME 114 8862
TEST_TIME 115 8842
TEST_TIME 116 8808
TEST_TIME 117 8778
TEST_TIME 118 8744
TEST_TIME 119 8763
TEST_TIME 120 8690

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2021 16:45:07 to 03-11-2021 16:45:10.

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 1450179 1450179 0 0.00 0 24608 7 0 0
doc_list_biud 1450175 1450175 0 0.00 0 21219 30 0 0
sp_add_invoice_to_stock 4794 4696 98 2.04 35 207736 20585 0 98
sp_cancel_adding_invoice 5299 5299 0 0.00 6 105905 802 0 0
sp_cancel_client_order 1674 1674 0 0.00 4 19173 372 0 0
sp_cancel_customer_reserve 520245 520245 0 0.00 1 21221 68 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 6862 101 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 4144 109 0 0
sp_cancel_supplier_invoice 5844 5838 6 0.10 2 20033 861 0 6
sp_cancel_supplier_order 396 396 0 0.00 4 1103078 112817 0 0
sp_cancel_write_off 8847 8847 0 0.00 1 24611 144 0 0
sp_client_order 19253 19253 0 0.00 3 16452 351 0 0
sp_customer_reserve 441159 441159 0 0.00 0 66077 200 0 0
sp_fill_shopping_cart 29598 29598 0 0.00 1 9365 40 0 0
sp_fill_shopping_cart_clo_res 441147 441147 0 0.00 0 17945 17 0 0
sp_get_clo_for_invoice 4682 4682 0 0.00 0 46369 393 0 0
sp_kill_cost_storno 15176 15176 0 0.00 0 24606 73 0 0
sp_kill_qstorno_ret_qs2qd 1055571 1055571 0 0.00 0 21216 36 0 0
sp_kill_qty_storno 556417 556417 0 0.00 0 21218 78 0 0
sp_lock_selected_doc 19601 19601 0 0.00 0 5926 5 0 0
sp_make_cost_storno 26386 26386 0 0.00 0 20125 55 0 0
sp_multiply_rows_for_pdistr 26385 26385 0 0.00 0 12161 33 0 0
sp_multiply_rows_for_qdistr 19253 19253 0 0.00 0 12889 163 0 0
sp_pay_from_customer 6668 6668 0 0.00 4 15744 120 0 0
sp_pay_to_supplier 5600 5600 0 0.00 5 14760 132 0 0
sp_payment_common 12267 12267 0 0.00 1 10908 31 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 19165 349 0 0
sp_qd_handle_on_invoice_upd_sts 9981 9981 0 0.00 0 19305 346 0 0
sp_qd_handle_on_reserve_upd_sts 9436 9436 0 0.00 0 11179 61 0 0
sp_reserve_write_off 9436 9436 0 0.00 6 17214 182 0 0
sp_supplier_invoice 5212 5212 0 0.00 6 79747 3590 0 0
sp_supplier_order 5135 5135 0 0.00 6 46081 1213 0 0
srv_aggregate_perf_data 93 93 0 0.00 2243 189787 19821 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 885 126307 44060 0 0
srv_find_qd_qs_mism 996652 996652 0 0.00 0 18954 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 59 41018 1102 0 0
srv_make_money_saldo 764 764 0 0.00 0 1708 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 178834 543362 364270 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4697 4697 0 0.00 0 15033 180 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 0 2723 87 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 7596 23 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 13781 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 3257 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 1019 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1208 18 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 25666 1753 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 1289 9 0 0
t$perf-norm:sp_client_order 19253 19253 0 0.00 0 16155 23 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 2165 6 0 0
t$perf-norm:sp_pay_from_customer 6668 6668 0 0.00 0 6165 10 0 0
t$perf-norm:sp_pay_to_supplier 5600 5600 0 0.00 0 16145 20 0 0
t$perf-norm:sp_reserve_write_off 9436 9436 0 0.00 0 13809 16 0 0
t$perf-norm:sp_supplier_invoice 5212 5212 0 0.00 0 6223 24 0 0
t$perf-norm:sp_supplier_order 5135 5135 0 0.00 0 7050 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 172 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 105 2 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 453 17 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 13906 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 9560 9364 196 2.05 0 5065 19 0 196
v_all_customers 35184 35184 0 0.00 0 16 1 0 0
v_all_suppliers 29204 29204 0 0.00 0 17 1 0 0
v_all_wares 38506 38506 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 1164 7 0 0
v_cancel_client_order 3348 3348 0 0.00 0 1943 4 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 5271 18 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1107 4 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 2683 9 0 0
v_cancel_supplier_invoice 760 748 12 1.57 0 2603 24 0 12
v_cancel_supplier_order 792 792 0 0.00 0 1747 8 0 0
v_cancel_write_off 1914 1914 0 0.00 0 2908 18 0 0
v_max_id_clo_ord 5134 5134 0 0.00 0 186 1 0 0
v_max_id_clo_res 961 961 0 0.00 0 4039 14 0 0
v_max_id_ord_sup 5211 5211 0 0.00 0 4401 5 0 0
v_max_non_paid_invoice 1342 1342 0 0.00 0 1901 5 0 0
v_max_non_paid_realizn 3443 3443 0 0.00 0 4460 6 0 0
v_min_id_clo_ord 5134 5134 0 0.00 0 5612 8 0 0
v_min_id_clo_res 961 961 0 0.00 0 3682 9 0 0
v_min_id_ord_sup 5211 5211 0 0.00 0 5296 8 0 0
v_min_non_paid_invoice 5599 5599 0 0.00 0 10850 24 0 0
v_min_non_paid_realizn 6668 6668 0 0.00 0 10831 23 0 0
v_our_firm 9772 9772 0 0.00 0 16 1 0 0
v_reserve_write_off 18870 18870 0 0.00 0 3392 5 0 0
x_lock_depdocs_on_canc_invoice 10763 10763 0 0.00 0 17140 130 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 13457 422 0 0
x_make_qstorno_1000_1200 5135 5135 0 0.00 2 46075 1133 0 0
x_make_qstorno_1200_2000 5212 5212 0 0.00 3 75064 3445 0 0
x_make_qstorno_2100_3300 441158 441158 0 0.00 0 66074 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2021 16:45:11 to 03-11-2021 16:45:15.

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:13:09 28 8192000000 8820470880 8839151616 23154384 0.281 6 5 7 0 23463440 112416 196640
14:14:10 24181 8192000000 9904711728 10220486656 989561936 10.777 100 86 171 1 1017652672 6094528 21996208
14:15:40 24591 8192000000 9964716704 10291814400 1043943744 11.303 100 91 182 0 1075894672 8749616 23201312
14:17:12 22060 8192000000 9998890992 10337419264 1082210256 11.669 100 81 162 0 1112592944 10663648 19719040
14:18:39 12777 8192000000 10030222080 10382528512 1119780928 12.025 100 75 150 0 1148105088 10152656 18171504
14:19:59 10424 8192000000 10078859984 10437562368 1163244368 12.434 100 82 163 0 1194345008 12711328 18389312
14:21:12 8908 8192000000 10123663936 10489237504 1206080320 12.833 100 73 146 0 1234216784 11604672 16531792
14:22:24 4947 8192000000 10143907664 10518212608 1236380992 13.113 100 78 157 0 1267110688 13759584 16970112
14:23:30 7522 8192000000 10176074128 10555564032 1258333024 13.315 100 80 160 0 1290166064 14531728 17301312
14:24:39 2490 8192000000 10200769296 10582065152 1282457136 13.535 100 88 176 1 1316273744 14095808 19720800
14:25:45 2407 8192000000 10217662928 10604027904 1301658784 13.710 100 95 189 1 1336130784 12350224 22121776
14:26:49 22236 8192000000 10204276448 10609082368 1304764560 13.739 100 53 104 2 1324955040 8192032 11998448
14:28:14 7163 8192000000 10210393568 10619940864 1313564128 13.818 100 30 61 1 1327352192 6542240 7245824
14:29:25 10379 8192000000 10190050496 10613374976 1312447984 13.808 100 21 43 1 1323403664 6275984 4679696
14:30:38 21405 8192000000 10214898496 10636705792 1325173296 13.924 100 26 52 4 1337361072 6616576 5571200
14:32:54 6472 8192000000 10231862416 10649784320 1331568368 13.981 100 30 60 0 1344959040 6292880 7097792
14:34:02 11294 8192000000 10206989888 10632462336 1320993536 13.886 100 22 44 1 1332046496 6194416 4858544
14:35:15 29868 8192000000 10192433888 10614439936 1271412944 13.435 96 21 42 0 1281608496 5242736 4952816
14:36:46 5997 8192000000 10175905280 10588917760 1265278384 13.378 98 38 76 3 1279891792 5316608 9296800
14:37:52 7342 8192000000 10118731152 10510737408 1190446256 12.688 95 16 32 1 1199722144 5311136 3964752
14:39:04 49776 8192000000 10143365376 10544484352 1252731424 13.263 100 29 58 0 1265318112 5186624 7400064
14:40:56 4021 8192000000 10159226032 10556264448 1260886496 13.338 100 28 55 1 1272948016 5374848 6686672
14:42:04 9873 8192000000 10183765728 10596933632 1288580880 13.591 100 15 30 2 1297079040 5185856 3312304
14:43:24 49570 8192000000 10220339968 10624176128 1315823888 13.839 100 39 76 1 1330121600 5565392 8732320
14:45:18 78002 8192000000 10248519072 10664001536 1342372256 14.079 100 49 98 1 1367909168 14176976 11359936
14:47:50 24454 8192000000 10263083632 10682134528 1356668784 14.207 100 45 91 1 1385180192 17538736 10972672
14:49:21 14472 8192000000 10276982960 10695970816 1371906480 14.344 100 41 82 3 1398468368 17639200 8922688
14:50:59 14212 8192000000 10311637600 10724646912 1382230656 14.436 100 56 110 1 1414929328 20318384 12380288
14:52:26 16270 8192000000 10324528736 10743660544 1395353872 14.554 100 54 107 1 1436113632 27231408 13528352
14:53:53 11255 8192000000 10330472944 10749886464 1399285440 14.589 100 54 106 1 1437943024 25669712 12987872
14:55:10 3811 8192000000 10354938944 10778992640 1417695696 14.752 100 59 117 0 1464616416 32307936 14612784
14:56:18 8403 8192000000 10374173040 10797027328 1423494368 14.804 100 59 116 1 1477060080 38837072 14728640
14:57:33 3490 8192000000 10383937328 10801864704 1437012592 14.923 100 82 160 0 1486603824 30981376 18609856
14:58:44 3452 8192000000 10393711984 10817740800 1450815312 15.045 100 77 154 0 1502889728 33292464 18781952
14:59:52 4376 8192000000 10381773952 10795622400 1416596352 14.743 99 78 155 0 1474136960 38209088 19331520
15:01:01 3927 8192000000 10396203312 10810413056 1429820816 14.860 99 81 161 2 1491215840 40341744 21053280
15:02:13 1954 8192000000 10395247280 10807250944 1428160672 14.845 100 85 169 0 1498497808 49397712 20939424
15:03:21 2578 8192000000 10393759344 10804678656 1421460800 14.786 100 88 175 2 1493698592 51056272 21181520
15:04:30 11474 8192000000 10342863488 10766782464 1386111488 14.471 100 53 106 1 1452120064 51624320 14384256
15:05:52 879 8192000000 10391495104 10798764032 1402093712 14.614 100 83 164 1 1488786592 64612336 22080544
15:06:59 9841 8192000000 10343033056 10783502336 1394869280 14.549 100 40 80 0 1450245024 44233232 11142512
15:08:13 28512 8192000000 10352501968 10789629952 1389448256 14.501 99 25 50 1 1442943488 46560096 6935136
15:09:43 25773 8192000000 10348423344 10791059456 1392681248 14.530 100 27 54 0 1450527024 49805264 8040512
15:11:22 35660 8192000000 10318237712 10767679488 1376385248 14.384 100 14 28 2 1425653360 45230192 4037920
15:12:59 13295 8192000000 10299495584 10732957696 1363820928 14.272 99 44 88 2 1382606128 9715072 9070128
15:14:26 111354 8192000000 10277918496 10715824128 1317032240 13.850 96 28 56 1 1336708384 13308960 6367184
15:17:18 53364 8192000000 10285902000 10736820224 1373602544 14.359 100 13 26 0 1390152192 13571232 2978416
15:20:40 52412 8192000000 10241855168 10694074368 1325228176 13.924 98 15 29 2 1346374624 17526352 3620096
15:22:34 42483 8192000000 10266131968 10716098560 1366482592 14.296 100 21 42 0 1381312288 10141248 4688448
15:24:35 71528 8192000000 10255348912 10715373568 1349512096 14.143 99 9 18 1 1363589616 11856752 2220768
15:26:47 44409 8192000000 10276187296 10727153664 1357580928 14.216 99 33 65 1 1380004256 15033408 7389920
15:28:33 56471 8192000000 10274257248 10724675584 1359131600 14.230 99 23 46 0 1381219968 16581648 5506720
15:30:35 93819 8192000000 10278627120 10741624832 1349278304 14.141 99 21 40 0 1373834368 19908592 4647472
15:33:16 3579 8192000000 10269970032 10720256000 1367338192 14.303 100 34 69 0 1385189552 9451616 8399744
15:34:21 79131 8192000000 10261317184 10727419904 1360941392 14.246 99 13 26 0 1372873248 8787376 3144480
15:36:40 44672 8192000000 10260900080 10718511104 1357567104 14.216 99 23 46 0 1370756464 8287056 4902304
15:38:27 59647 8192000000 10267892800 10727079936 1335653680 14.018 97 15 30 0 1349448080 10077232 3717168
15:40:32 48682 8192000000 10296761040 10757632000 1381807216 14.433 100 27 54 0 1402794448 14450176 6537056
15:42:53 65679 8192000000 10299451376 10762829824 1357579280 14.216 97 15 30 1 1381816032 20666464 3570288
15:45:02 40969 8192000000 10297900416 10758971392 1381752576 14.432 99 20 40 2 1394493168 8478656 4261936
15:46:44 10563 8192000000 10309351456 10750844928 1388864496 14.496 99 50 100 1 1409828928 9201584 11762848
15:47:57 38368 8192000000 10287762576 10747375616 1373234480 14.356 98 28 56 0 1394073328 14362880 6475968
15:49:35 70028 8192000000 10295781952 10757898240 1351626144 14.162 97 21 40 2 1375793136 19212768 4954224
15:52:11 34104 8192000000 10277130720 10724777984 1323527056 13.909 96 38 76 0 1345062544 13355008 8180480
15:53:47 92969 8192000000 10279140800 10730389504 1338636768 14.045 97 9 18 1 1355811744 15011680 2163296
15:56:23 52994 8192000000 10286926928 10739036160 1366144736 14.292 99 25 50 0 1381621472 9749008 5727728
15:58:26 79483 8192000000 10270192736 10738900992 1376637248 14.386 100 9 18 0 1390536480 11925872 1973360
16:00:50 45543 8192000000 10272418016 10722394112 1367301280 14.303 99 17 34 0 1382715984 11299248 4115456
16:02:53 83564 8192000000 10267559008 10723246080 1359202128 14.230 99 12 25 2 1373642320 11421376 3018816
16:05:19 33928 8192000000 10266245072 10722263040 1342458736 14.080 98 15 29 0 1353161072 7654112 3048224
16:07:07 92458 8192000000 10265436528 10711195648 1345596240 14.108 98 17 34 1 1362325936 12900096 3829600
16:09:40 53533 8192000000 10276878288 10729340928 1333433488 13.998 97 16 32 0 1349921136 13113936 3373712
16:11:34 67460 8192000000 10268634928 10726653952 1355018208 14.193 99 7 14 0 1369474560 12966432 1489920
16:13:43 46977 8192000000 10279657712 10711425024 1328455344 13.953 97 30 58 1 1347189456 12318432 6415680
16:15:34 42803 8192000000 10281650576 10726891520 1354645136 14.189 99 17 34 0 1368369168 10233184 3490848
16:17:16 103583 8192000000 10277789104 10722488320 1354426736 14.187 99 40 78 2 1373055424 10217312 8411376
16:21:15 16811 8192000000 10255639216 10719895552 1362923968 14.264 100 12 24 3 1375644928 9943216 2777744
16:22:36 45450 8192000000 10267288048 10720800768 1355201696 14.194 99 12 24 0 1364914384 7217472 2495216
16:24:25 55212 8192000000 10265605888 10711212032 1321181472 13.887 96 21 42 0 1337454720 11945568 4327680
16:26:25 98247 8192000000 10282283888 10739597312 1376932992 14.389 100 10 20 1 1395821312 16754064 2134256
16:29:05 38954 8192000000 10281199264 10725654528 1352300368 14.168 98 28 56 1 1367031920 8486192 6245360
16:30:46 54137 8192000000 10283570944 10731847680 1352966576 14.174 98 27 54 0 1369177392 9850208 6360608
16:32:43 29347 8192000000 10303245232 10743451648 1382849536 14.442 100 35 70 0 1402216528 11308784 8058208
16:34:23 82480 8192000000 10284206256 10734772224 1344058112 14.094 97 12 24 0 1362109056 15519744 2531200
16:36:50 126307 8192000000 10271350928 10722324480 1328244320 13.951 96 24 48 0 1342698304 9125424 5328560
16:40:05 21448 8192000000 10289558160 10741530624 1383442688 14.447 100 24 47 1 1403537040 14939616 5154736
16:41:27 69180 8192000000 10283726544 10751184896 1372168352 14.346 99 7 14 0 1394433584 20651168 1614064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2021 16:45:17 to 03-11-2021 16:45:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2021 16:45:33 to 03-11-2021 16:45:35.

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          222449
MON$OLDEST_ACTIVE               226135
MON$OLDEST_SNAPSHOT             226135
MON$NEXT_TRANSACTION            226135
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                       1998784
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33527 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33527 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33527 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33527 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Nov  3 16:43:15 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Nov  3 16:43:22 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Nov  3 16:43:22 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Wed Nov  3 16:43:22 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Wed Nov  3 16:43:22 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

03.11.21 16:45:36. 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
2021-11-03 16:45:38 No severe PSQL-related problems occured
03.11.21 16:45:39. End of report.