Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.09.2021 01:38
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 16040788 kB
MemAvailable 30889240 kB
Buffers 0 kB
Cached 15622500 kB
SwapCached 336 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-09-02 01:37:48
DTS_END                         2021-09-02 01:37:48.7400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51890
TRN_ID                          243314
ATT_ID                          725
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: 9583.98 22469 1150078
customer order: creation 173.53 362 20824
customer order: refuse 14.69 310 1763
order to supplier: creation 46.34 1255 5561
order to supplier: removal 3.57 104893 428
invoice (draft): creation 47.51 3341 5701
invoice (draft): removal 54.32 807 6518
invoice accept: apply 42.83 17553 5140
invoice accept: cancel 49.88 809 5986
customer reserve: creation 3932.31 175 471877
customer reserve: removal 4903.92 61 588470
realization accept: apply 85.71 177 10285
realization accept: cancel 95.53 104 11464
payment from customer: creation 61.53 114 7383
payment from customer: removal 5.90 58 708
payment to supplier: creation 50.14 122 6017
payment to supplier: removal 3.59 60 431
service: total inventory turnovers 5.15 998 618
service: total monetary turnovers 6.61 57 793
service: aggregate perf. data 0.89 14854 107
service: refresh index statistics 0.03 303261 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-09-2021 01:38:43 to 02-09-2021 01:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1986
TEST_TIME 2 3163
TEST_TIME 3 4147
TEST_TIME 4 4203
TEST_TIME 5 4383
TEST_TIME 6 4257
TEST_TIME 7 4456
TEST_TIME 8 5015
TEST_TIME 9 5056
TEST_TIME 10 5177
TEST_TIME 11 5233
TEST_TIME 12 5603
TEST_TIME 13 5981
TEST_TIME 14 6330
TEST_TIME 15 6325
TEST_TIME 16 6442
TEST_TIME 17 7037
TEST_TIME 18 7721
TEST_TIME 19 8175
TEST_TIME 20 8132
TEST_TIME 21 8345
TEST_TIME 22 8400
TEST_TIME 23 9156
TEST_TIME 24 9370
TEST_TIME 25 9755
TEST_TIME 26 10201
TEST_TIME 27 11176
TEST_TIME 28 11030
TEST_TIME 29 10964
TEST_TIME 30 10896
TEST_TIME 31 10833
TEST_TIME 32 10586
TEST_TIME 33 10360
TEST_TIME 34 10500
TEST_TIME 35 10347
TEST_TIME 36 10135
TEST_TIME 37 10171
TEST_TIME 38 10137
TEST_TIME 39 10073
TEST_TIME 40 9991
TEST_TIME 41 10132
TEST_TIME 42 10180
TEST_TIME 43 10095
TEST_TIME 44 9995
TEST_TIME 45 10029
TEST_TIME 46 10022
TEST_TIME 47 9988
TEST_TIME 48 9940
TEST_TIME 49 10056
TEST_TIME 50 10087
TEST_TIME 51 10081
TEST_TIME 52 10105
TEST_TIME 53 10112
TEST_TIME 54 10098
TEST_TIME 55 10109
TEST_TIME 56 10124
TEST_TIME 57 10174
TEST_TIME 58 10169
TEST_TIME 59 10032
TEST_TIME 60 10058
TEST_TIME 61 9972
TEST_TIME 62 9939
TEST_TIME 63 9922
TEST_TIME 64 9896
TEST_TIME 65 9801
TEST_TIME 66 9777
TEST_TIME 67 9783
TEST_TIME 68 9716
TEST_TIME 69 9771
TEST_TIME 70 9757
TEST_TIME 71 9786
TEST_TIME 72 9716
TEST_TIME 73 9739
TEST_TIME 74 9681
TEST_TIME 75 9788
TEST_TIME 76 9799
TEST_TIME 77 9794
TEST_TIME 78 9895
TEST_TIME 79 9943
TEST_TIME 80 9957
TEST_TIME 81 9953
TEST_TIME 82 9850
TEST_TIME 83 9796
TEST_TIME 84 9784
TEST_TIME 85 9755
TEST_TIME 86 9754
TEST_TIME 87 9699
TEST_TIME 88 9646
TEST_TIME 89 9680
TEST_TIME 90 9585
TEST_TIME 91 9628
TEST_TIME 92 9665
TEST_TIME 93 9642
TEST_TIME 94 9642
TEST_TIME 95 9659
TEST_TIME 96 9724
TEST_TIME 97 9772
TEST_TIME 98 9768
TEST_TIME 99 9815
TEST_TIME 100 9838
TEST_TIME 101 9868
TEST_TIME 102 9831
TEST_TIME 103 9858
TEST_TIME 104 9857
TEST_TIME 105 9844
TEST_TIME 106 9803
TEST_TIME 107 9741
TEST_TIME 108 9688
TEST_TIME 109 9714
TEST_TIME 110 9663
TEST_TIME 111 9670
TEST_TIME 112 9630
TEST_TIME 113 9594
TEST_TIME 114 9588
TEST_TIME 115 9623
TEST_TIME 116 9583
TEST_TIME 117 9597
TEST_TIME 118 9583
TEST_TIME 119 9580
TEST_TIME 120 9585

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-09-2021 01:38:47 to 02-09-2021 01:38:50.

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 1587432 1587432 0 0.00 0 22185 6 0 0
doc_list_biud 1587432 1587432 0 0.00 0 28302 28 0 0
sp_add_invoice_to_stock 5238 5140 98 1.87 19 194950 17554 0 98
sp_cancel_adding_invoice 5986 5986 0 0.00 4 92160 809 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 11220 311 0 0
sp_cancel_customer_reserve 588470 588470 0 0.00 1 28314 61 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 2951 59 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 2401 60 0 0
sp_cancel_supplier_invoice 6525 6518 7 0.10 2 12160 808 0 7
sp_cancel_supplier_order 428 428 0 0.00 7 1066862 104894 0 0
sp_cancel_write_off 11464 11464 0 0.00 1 6336 104 0 0
sp_client_order 20824 20824 0 0.00 4 25795 363 0 0
sp_customer_reserve 471877 471877 0 0.00 0 81511 176 0 0
sp_fill_shopping_cart 32084 32084 0 0.00 1 12346 39 0 0
sp_fill_shopping_cart_clo_res 471875 471875 0 0.00 0 11473 15 0 0
sp_get_clo_for_invoice 5138 5138 0 0.00 0 18201 306 0 0
sp_kill_cost_storno 18589 18589 0 0.00 0 6333 52 0 0
sp_kill_qstorno_ret_qs2qd 1195350 1195350 0 0.00 0 28064 33 0 0
sp_kill_qty_storno 630051 630051 0 0.00 0 28301 70 0 0
sp_lock_selected_doc 21340 21340 0 0.00 0 9399 5 0 0
sp_make_cost_storno 28822 28822 0 0.00 0 22182 52 0 0
sp_multiply_rows_for_pdistr 28822 28822 0 0.00 0 8977 26 0 0
sp_multiply_rows_for_qdistr 20824 20824 0 0.00 0 11428 172 0 0
sp_pay_from_customer 7383 7383 0 0.00 4 22734 115 0 0
sp_pay_to_supplier 6017 6017 0 0.00 5 22192 122 0 0
sp_payment_common 13400 13400 0 0.00 2 16757 25 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 9563 282 0 0
sp_qd_handle_on_invoice_upd_sts 11123 11123 0 0.00 0 12619 304 0 0
sp_qd_handle_on_reserve_upd_sts 10285 10285 0 0.00 0 13316 56 0 0
sp_reserve_write_off 10285 10285 0 0.00 6 23815 178 0 0
sp_supplier_invoice 5701 5701 0 0.00 7 82813 3342 0 0
sp_supplier_order 5561 5561 0 0.00 6 66077 1256 0 0
srv_aggregate_perf_data 107 107 0 0.00 3221 112093 14854 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1173 132744 42409 0 0
srv_find_qd_qs_mism 1101474 1101474 0 0.00 0 20352 5 0 0
srv_make_invnt_saldo 618 618 0 0.00 32 40978 998 0 0
srv_make_money_saldo 793 793 0 0.00 1 21132 57 0 0
srv_recalc_idx_stat 4 4 0 0.00 203102 404002 303262 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 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5140 5140 0 0.00 0 11757 171 0 0
t$perf-norm:sp_cancel_adding_invoice 462 462 0 0.00 0 5767 85 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 7602 12 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 3202 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 3207 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 242 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 2792 27 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 24212 1546 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 2095 11 0 0
t$perf-norm:sp_client_order 20824 20824 0 0.00 0 20600 20 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 13359 23 0 0
t$perf-norm:sp_pay_from_customer 7383 7383 0 0.00 0 20618 12 0 0
t$perf-norm:sp_pay_to_supplier 6017 6017 0 0.00 0 7659 10 0 0
t$perf-norm:sp_reserve_write_off 10285 10285 0 0.00 0 4385 11 0 0
t$perf-norm:sp_supplier_invoice 5701 5701 0 0.00 0 5624 16 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 9735 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 494 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 23 1 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 716 17 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 5589 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10470 10274 196 1.87 0 6517 21 0 196
v_all_customers 38692 38692 0 0.00 0 79 1 0 0
v_all_suppliers 31294 31294 0 0.00 0 78 1 0 0
v_all_wares 41648 41648 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 924 924 0 0.00 0 375 6 0 0
v_cancel_client_order 3526 3526 0 0.00 0 2377 7 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 1968 6 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 1578 7 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 2352 8 0 0
v_cancel_supplier_invoice 850 836 14 1.64 0 2531 18 0 14
v_cancel_supplier_order 856 856 0 0.00 0 24412 48 0 0
v_cancel_write_off 2066 2066 0 0.00 0 2904 16 0 0
v_max_id_clo_ord 5560 5560 0 0.00 0 2175 2 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 1685 11 0 0
v_max_id_ord_sup 5700 5700 0 0.00 0 6181 7 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 3444 5 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 968 3 0 0
v_min_id_clo_ord 5560 5560 0 0.00 0 4316 8 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 7960 17 0 0
v_min_id_ord_sup 5700 5700 0 0.00 0 8756 10 0 0
v_min_non_paid_invoice 6017 6017 0 0.00 0 5325 15 0 0
v_min_non_paid_realizn 7383 7383 0 0.00 0 9911 16 0 0
v_our_firm 10198 10198 0 0.00 0 78 1 0 0
v_reserve_write_off 20570 20570 0 0.00 0 23771 9 0 0
x_lock_depdocs_on_canc_invoice 12086 12086 0 0.00 0 26823 118 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 3342 306 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 2 66031 1183 0 0
x_make_qstorno_1200_2000 5701 5701 0 0.00 3 79316 3198 0 0
x_make_qstorno_2100_3300 471877 471877 0 0.00 0 81509 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-09-2021 01:38:51 to 02-09-2021 01:38:55.

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:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:53 25768 8192000000 10363958496 10683809792 1417483120 14.750 100 85 170 0 1451224576 6166432 27575024
23:10:26 6877 8192000000 10423886128 10751365120 1485186944 15.347 100 91 182 0 1523149952 7983552 29979456
23:11:36 28130 8192000000 10478110016 10817454080 1532484656 15.759 100 91 182 0 1570931184 9610928 28835600
23:13:09 15896 8192000000 10561350560 10920177664 1621339728 16.521 100 82 164 2 1657985856 11429408 25216720
23:14:29 5923 8192000000 10632407312 10998009856 1696280480 17.154 100 85 170 0 1734512880 13237104 24995296
23:15:39 5557 8192000000 10694755472 11071619072 1756741024 17.657 100 80 161 1 1792324352 13751952 21831376
23:16:47 6120 8192000000 10739124896 11123679232 1799733584 18.012 100 83 165 0 1836027424 13442368 22851472
23:17:58 1383 8192000000 10798877232 11182714880 1850369488 18.425 100 93 188 1 1889984656 13336368 26278800
23:19:00 1595 8192000000 10821253760 11209568256 1877401168 18.644 100 95 190 1 1917462240 12699312 27361760
23:20:03 6060 8192000000 10798220480 11216068608 1875555264 18.629 100 44 88 0 1897847184 10464912 11827008
23:21:10 10236 8192000000 10793753040 11215171584 1877119456 18.642 100 28 56 1 1891064480 5866672 8078352
23:22:20 8520 8192000000 10810920080 11232894976 1895442960 18.790 100 21 41 1 1907098336 6133520 5521856
23:23:29 19216 8192000000 10853709264 11255623680 1908182112 18.892 100 68 137 2 1933588576 5972960 19433504
23:24:49 4514 8192000000 10827821200 11256381440 1914172608 18.940 100 38 75 2 1932733376 8004736 10556032
23:25:55 8679 8192000000 10821768048 11249963008 1902570848 18.847 100 17 34 0 1912267808 4588624 5108336
23:27:06 67458 8192000000 10788290784 11218817024 1825296624 18.221 96 32 64 2 1840082336 4945744 9839968
23:29:17 4911 8192000000 10757598128 11187052544 1847763056 18.404 100 26 50 1 1860377568 5889440 6725072
23:30:23 8506 8192000000 10745967808 11165360128 1816899200 18.152 99 30 60 1 1832050144 6038512 9112432
23:31:36 25559 8192000000 10730010176 11159531520 1805825856 18.062 99 31 62 1 1820453904 5817024 8811024
23:33:28 7573 8192000000 10753293856 11175583744 1839902848 18.340 100 30 60 2 1854051392 6010784 8137760
23:34:36 14132 8192000000 10764965472 11197837312 1853789328 18.453 100 24 48 1 1866758944 5330336 7639280
23:35:53 11326 8192000000 10784303952 11217776640 1873561056 18.613 100 18 36 0 1884083536 4607216 5915264
23:37:12 60590 8192000000 10811342512 11245613056 1899880432 18.825 100 26 51 2 1912255440 4567808 7807200
23:39:33 14447 8192000000 10875592768 11294121984 1938487760 19.135 100 55 111 0 1965074368 10797216 15789392
23:40:59 17383 8192000000 10905011024 11326976000 1960088080 19.307 100 48 95 1 1990362528 16595040 13679408
23:42:25 21205 8192000000 10916221568 11348283392 1973226752 19.411 100 43 83 4 2007484048 21744576 12512720
23:43:53 14663 8192000000 10929757392 11360378880 1981152096 19.474 100 51 102 1 2025329424 29328448 14848880
23:45:46 21283 8192000000 10939161424 11367403520 1971468960 19.397 99 47 93 3 2010309072 25195280 13644832
23:47:09 7912 8192000000 10966762320 11402997760 2003919856 19.654 100 49 97 2 2054377984 35736896 14721232
23:48:23 1173 8192000000 10969110912 11386601472 1980673616 19.470 99 79 154 0 2046579472 42974896 22930960
23:49:28 1970 8192000000 10997660576 11418914816 2012533152 19.721 100 74 148 3 2083547824 48438240 22576432
23:50:36 1260 8192000000 11006375696 11424325632 2014392480 19.736 100 84 168 1 2092251584 52192368 25666736
23:51:43 3827 8192000000 11006977488 11431702528 1999268592 19.617 99 77 152 0 2076236016 53075792 23891632
23:52:52 2098 8192000000 11023852032 11435687936 2010801744 19.708 100 88 175 2 2100166064 62088864 27275456
23:53:56 3622 8192000000 11039068176 11454451712 2019274032 19.774 100 80 155 2 2113802288 69179184 25349072
23:55:03 3359 8192000000 10991359072 11413184512 1971173360 19.395 99 80 160 2 2062853040 66097264 25582416
23:56:12 2969 8192000000 10956035520 11386769408 1965394592 19.349 100 60 121 1 2041808800 57243312 19170896
23:57:17 10222 8192000000 10943373616 11388760064 1957521504 19.286 99 35 70 0 2019708192 50266992 11919696
23:58:32 16565 8192000000 10954044000 11404431360 1979503232 19.461 100 34 68 0 2041547744 50161920 11882592
23:59:59 18318 8192000000 10932661728 11376230400 1920861952 18.994 98 40 80 1 1992720608 57887088 13971568
00:01:18 25788 8192000000 10929825760 11388432384 1966934288 19.361 100 29 58 2 2025785424 48464976 10386160
00:02:58 24861 8192000000 10905179648 11361755136 1925740896 19.033 99 22 44 1 1982508208 48746736 8020576
00:04:29 36701 8192000000 10845515328 11308728320 1877012176 18.641 97 9 17 1 1901189136 21609840 2567120
00:06:06 25085 8192000000 10844394528 11301634048 1913024496 18.931 100 12 24 2 1925456448 9242912 3189040
00:07:39 101394 8192000000 10872065104 11329286144 1901686144 18.840 98 11 22 1 1914764816 9761248 3317424
00:10:21 53724 8192000000 10906544096 11342635008 1930285616 19.069 98 58 116 1 1957923520 10954128 16683776
00:12:25 27276 8192000000 10900106784 11350614016 1958649680 19.295 100 41 82 0 1977915344 7236736 12028928
00:13:53 32358 8192000000 10881626288 11338625024 1931351936 19.078 98 26 52 2 1947366528 8386112 7628480
00:15:26 44347 8192000000 10887113296 11340095488 1950968928 19.234 100 29 56 4 1968582848 9210048 8403872
00:17:40 5944 8192000000 10921630848 11359252480 1970300976 19.388 100 62 125 0 2005737520 16751440 18685104
00:18:47 40876 8192000000 10887488576 11367444480 1963687168 19.335 100 12 24 0 1977982176 10807968 3487040
00:21:02 96959 8192000000 10891118880 11370475520 1954254832 19.260 99 12 24 0 1970359264 12350848 3753584
00:23:45 31733 8192000000 10881941392 11357532160 1947112464 19.203 99 20 39 0 1959524128 6877648 5534016
00:25:19 53721 8192000000 10906223840 11379666944 1975016176 19.425 100 14 28 0 1990533296 12015680 3501440
00:27:13 53214 8192000000 10904092560 11378139136 1967432112 19.365 99 21 42 0 1982431008 8499840 6499056
00:29:07 68926 8192000000 10894348576 11369881600 1869702816 18.582 94 12 24 0 1893225712 20333520 3189376
00:32:01 91953 8192000000 10896509808 11371032576 1876099888 18.634 95 12 24 0 1890435776 10831536 3504352
00:34:34 51115 8192000000 10903377840 11359428608 1924159552 19.020 97 29 56 6 1941453200 8904368 8389280
00:36:29 99769 8192000000 10887879232 11365818368 1939221744 19.141 98 25 50 1 1954790672 8944144 6624784
00:39:09 27454 8192000000 10871115680 11343544320 1936329664 19.117 99 18 36 0 1953773264 12161680 5281920
00:40:39 55525 8192000000 10870328288 11344125952 1932885776 19.090 98 13 26 0 1945596064 9349248 3361040
00:42:35 53441 8192000000 10882464608 11357179904 1965309136 19.348 100 10 20 0 1976592960 8402048 2881776
00:44:35 49973 8192000000 10867107840 11327143936 1853031936 18.447 94 12 24 1 1866190720 9786800 3371984
00:46:31 26843 8192000000 10865711104 11322257408 1871962768 18.600 96 13 26 0 1882599280 6697216 3939296
00:48:04 59531 8192000000 10848055120 11320811520 1853436192 18.450 95 21 42 1 1867506224 8637680 5432352
00:50:03 69166 8192000000 10875913760 11332063232 1909440048 18.902 98 27 54 0 1931694960 14814656 7440256
00:52:20 38409 8192000000 10884637728 11361181696 1929944592 19.066 99 27 54 2 1954190384 16827504 7418288
00:54:01 5306 8192000000 10918568384 11361165312 1960088880 19.307 100 62 124 1 1991714080 14560208 17064992
00:55:11 50313 8192000000 10912473984 11372503040 1940160640 19.148 99 21 41 0 1966408672 20404480 5843552
00:57:26 113001 8192000000 10855831600 11325616128 1837632080 18.322 94 13 24 0 1858873472 16852480 4388912
01:00:19 40328 8192000000 10866424480 11319656448 1921621984 19.000 99 17 34 1 1932669664 6730240 4317440
01:02:01 17909 8192000000 10870745056 11347177472 1949112848 19.219 100 22 45 0 1965612176 10040992 6458336
01:03:45 49670 8192000000 10886967808 11373629440 1965726400 19.352 100 3 6 1 1983877632 17335472 815760
01:06:37 75703 8192000000 10884362320 11364528128 1958726816 19.296 100 14 26 0 1976299216 13538608 4033792
01:08:54 94352 8192000000 10883232608 11366580224 1927924848 19.050 98 11 22 1 1943047088 12033840 3088400
01:11:28 55002 8192000000 10929155600 11400491008 1998557312 19.611 100 18 36 1 2016830784 13460240 4813232
01:13:23 47407 8192000000 10936586864 11406057472 1970253072 19.387 98 22 43 2 1998882416 21987264 6642080
01:15:20 60587 8192000000 10927571856 11399012352 1930751824 19.073 97 18 36 2 1949187520 13527008 4908688
01:17:22 53994 8192000000 10914479344 11378552832 1946904096 19.202 98 21 42 0 1963010832 10278368 5828368
01:19:16 23736 8192000000 10913879776 11372072960 1940306976 19.149 98 46 91 0 1968621296 14893536 13420784
01:20:48 70114 8192000000 10923728016 11387584512 1957959968 19.290 99 19 38 1 1973201104 10104576 5136560
01:23:00 132744 8192000000 10885392544 11346157568 1934868352 19.106 98 13 26 0 1952789616 14623664 3297600
01:26:13 57607 8192000000 10891240688 11387138048 1974728064 19.423 100 11 22 0 1994647248 16181056 3738128
01:28:46 75921 8192000000 10879125488 11355029504 1896763200 18.800 96 11 22 0 1912147344 12534224 2849920
01:31:09 39425 8192000000 10880533744 11351187456 1928840400 19.058 99 15 29 2 1951450576 18288096 4322080
01:32:50 77270 8192000000 10853099504 11319463936 1859009392 18.495 95 11 22 0 1871093488 9195088 2889008
01:35:10 51068 8192000000 10828635584 11290652672 1821453536 18.190 94 22 44 1 1835486544 7979072 6053936
01:37:18 38505 8192000000 9029614960 9233100800 200440304 2.388 11 1 2 0 201757984 1255552 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-09-2021 01:38:57 to 02-09-2021 01:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 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 02-09-2021 01:39:13 to 02-09-2021 01:39:15.

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          243658
MON$OLDEST_ACTIVE               243659
MON$OLDEST_SNAPSHOT             243659
MON$NEXT_TRANSACTION            243659
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                       2044064
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.33490 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33490 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33490 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33490 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'

02.09.21 01:39:16. 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-09-02 01:39:18 No severe PSQL-related problems occured
02.09.21 01:39:19. End of report.