Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2021 19: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 16378532 kB
MemAvailable 30940284 kB
Buffers 0 kB
Cached 15286848 kB
SwapCached 3640 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-08-21 19:37:46
DTS_END                         2021-08-21 19:37:46.6750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43556
TRN_ID                          242415
ATT_ID                          731
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: 9190.73 25276 1102888
customer order: creation 170.48 390 20457
customer order: refuse 14.50 381 1740
order to supplier: creation 45.68 1296 5482
order to supplier: removal 3.43 102059 411
invoice (draft): creation 45.23 3502 5427
invoice (draft): removal 53.95 805 6474
invoice accept: apply 41.75 18795 5010
invoice accept: cancel 48.15 754 5778
customer reserve: creation 3774.35 190 452922
customer reserve: removal 4689.65 58 562758
realization accept: apply 83.28 208 9993
realization accept: cancel 90.15 112 10818
payment from customer: creation 58.99 130 7079
payment from customer: removal 5.89 97 707
payment to supplier: creation 49.29 154 5915
payment to supplier: removal 3.38 89 405
service: total inventory turnovers 4.96 971 595
service: total monetary turnovers 6.87 25 824
service: aggregate perf. data 0.74 22998 89
service: refresh index statistics 0.03 352506 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2021 19:38:48 to 21-08-2021 19:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5341
TEST_TIME 2 3201
TEST_TIME 3 4133
TEST_TIME 4 3970
TEST_TIME 5 3989
TEST_TIME 6 4245
TEST_TIME 7 4744
TEST_TIME 8 4496
TEST_TIME 9 4712
TEST_TIME 10 4878
TEST_TIME 11 4916
TEST_TIME 12 5100
TEST_TIME 13 5268
TEST_TIME 14 5476
TEST_TIME 15 5631
TEST_TIME 16 6050
TEST_TIME 17 6514
TEST_TIME 18 6876
TEST_TIME 19 7420
TEST_TIME 20 8040
TEST_TIME 21 8159
TEST_TIME 22 8001
TEST_TIME 23 8469
TEST_TIME 24 8729
TEST_TIME 25 9282
TEST_TIME 26 10282
TEST_TIME 27 10351
TEST_TIME 28 10061
TEST_TIME 29 9975
TEST_TIME 30 9841
TEST_TIME 31 9743
TEST_TIME 32 9742
TEST_TIME 33 9657
TEST_TIME 34 9532
TEST_TIME 35 9399
TEST_TIME 36 9462
TEST_TIME 37 9253
TEST_TIME 38 9142
TEST_TIME 39 9130
TEST_TIME 40 9163
TEST_TIME 41 9200
TEST_TIME 42 9223
TEST_TIME 43 9194
TEST_TIME 44 9290
TEST_TIME 45 9212
TEST_TIME 46 9133
TEST_TIME 47 9169
TEST_TIME 48 9341
TEST_TIME 49 9488
TEST_TIME 50 9424
TEST_TIME 51 9354
TEST_TIME 52 9308
TEST_TIME 53 9470
TEST_TIME 54 9551
TEST_TIME 55 9398
TEST_TIME 56 9464
TEST_TIME 57 9403
TEST_TIME 58 9354
TEST_TIME 59 9341
TEST_TIME 60 9300
TEST_TIME 61 9239
TEST_TIME 62 9216
TEST_TIME 63 9124
TEST_TIME 64 9154
TEST_TIME 65 9142
TEST_TIME 66 9166
TEST_TIME 67 9118
TEST_TIME 68 9216
TEST_TIME 69 9170
TEST_TIME 70 9187
TEST_TIME 71 9170
TEST_TIME 72 9215
TEST_TIME 73 9184
TEST_TIME 74 9178
TEST_TIME 75 9290
TEST_TIME 76 9358
TEST_TIME 77 9365
TEST_TIME 78 9355
TEST_TIME 79 9442
TEST_TIME 80 9384
TEST_TIME 81 9321
TEST_TIME 82 9255
TEST_TIME 83 9183
TEST_TIME 84 9180
TEST_TIME 85 9204
TEST_TIME 86 9146
TEST_TIME 87 9139
TEST_TIME 88 9138
TEST_TIME 89 9133
TEST_TIME 90 9154
TEST_TIME 91 9165
TEST_TIME 92 9159
TEST_TIME 93 9205
TEST_TIME 94 9204
TEST_TIME 95 9287
TEST_TIME 96 9277
TEST_TIME 97 9273
TEST_TIME 98 9266
TEST_TIME 99 9310
TEST_TIME 100 9353
TEST_TIME 101 9383
TEST_TIME 102 9380
TEST_TIME 103 9342
TEST_TIME 104 9267
TEST_TIME 105 9231
TEST_TIME 106 9208
TEST_TIME 107 9186
TEST_TIME 108 9135
TEST_TIME 109 9093
TEST_TIME 110 9062
TEST_TIME 111 9086
TEST_TIME 112 9150
TEST_TIME 113 9184
TEST_TIME 114 9164
TEST_TIME 115 9179
TEST_TIME 116 9212
TEST_TIME 117 9178
TEST_TIME 118 9199
TEST_TIME 119 9201
TEST_TIME 120 9195

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-08-2021 19:38:51 to 21-08-2021 19:38:55.

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 1523286 1523286 0 0.00 0 21427 7 0 0
doc_list_biud 1523283 1523283 0 0.00 0 60532 28 0 0
sp_add_invoice_to_stock 5079 5010 69 1.35 33 173309 18795 0 69
sp_cancel_adding_invoice 5778 5778 0 0.00 5 67827 755 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 18524 382 0 0
sp_cancel_customer_reserve 562758 562758 0 0.00 1 60534 59 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 6611 97 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4432 89 0 0
sp_cancel_supplier_invoice 6480 6474 6 0.09 2 13997 806 0 6
sp_cancel_supplier_order 411 411 0 0.00 7 1028984 102059 0 0
sp_cancel_write_off 10818 10818 0 0.00 1 19239 112 0 0
sp_client_order 20457 20457 0 0.00 3 43534 390 0 0
sp_customer_reserve 452922 452922 0 0.00 0 53972 191 0 0
sp_fill_shopping_cart 31359 31359 0 0.00 1 19676 43 0 0
sp_fill_shopping_cart_clo_res 452915 452915 0 0.00 0 30018 16 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 36976 326 0 0
sp_kill_cost_storno 17708 17708 0 0.00 0 19236 56 0 0
sp_kill_qstorno_ret_qs2qd 1143219 1143219 0 0.00 0 60527 32 0 0
sp_kill_qty_storno 602974 602974 0 0.00 0 60532 69 0 0
sp_lock_selected_doc 20782 20782 0 0.00 0 7529 7 0 0
sp_make_cost_storno 27990 27990 0 0.00 0 16550 70 0 0
sp_multiply_rows_for_pdistr 27989 27989 0 0.00 0 6558 32 0 0
sp_multiply_rows_for_qdistr 20457 20457 0 0.00 0 43525 186 0 0
sp_pay_from_customer 7079 7079 0 0.00 4 22258 130 0 0
sp_pay_to_supplier 5915 5915 0 0.00 5 16746 154 0 0
sp_payment_common 12994 12994 0 0.00 2 18353 33 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 18517 357 0 0
sp_qd_handle_on_invoice_upd_sts 10781 10781 0 0.00 0 30612 317 0 0
sp_qd_handle_on_reserve_upd_sts 9992 9992 0 0.00 0 23187 75 0 0
sp_reserve_write_off 9993 9993 0 0.00 6 23309 209 0 0
sp_supplier_invoice 5427 5427 0 0.00 8 104041 3503 0 0
sp_supplier_order 5482 5482 0 0.00 6 69065 1296 0 0
srv_aggregate_perf_data 89 89 0 0.00 2520 358895 22999 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1710 127534 42608 0 0
srv_find_qd_qs_mism 1056081 1056081 0 0.00 0 12607 6 0 0
srv_make_invnt_saldo 595 595 0 0.00 25 54200 971 0 0
srv_make_money_saldo 824 824 0 0.00 1 1284 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 144325 469625 352507 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 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 5010 5010 0 0.00 0 12002 157 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 7479 121 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4686 15 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 2403 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1579 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 122 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 2152 19 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 26798 1621 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 4242 15 0 0
t$perf-norm:sp_client_order 20457 20457 0 0.00 0 13108 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 8228 18 0 0
t$perf-norm:sp_pay_from_customer 7079 7079 0 0.00 0 5294 8 0 0
t$perf-norm:sp_pay_to_supplier 5915 5915 0 0.00 0 5486 9 0 0
t$perf-norm:sp_reserve_write_off 9993 9993 0 0.00 0 13274 12 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 5044 18 0 0
t$perf-norm:sp_supplier_order 5482 5482 0 0.00 0 5082 15 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 76 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 994 16 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 1566 22 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 9600 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 10144 10006 138 1.36 0 5406 22 0 138
v_all_customers 37210 37210 0 0.00 0 23 1 0 0
v_all_suppliers 30322 30322 0 0.00 0 26 1 0 0
v_all_wares 40914 40914 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 3056 12 0 0
v_cancel_client_order 3480 3480 0 0.00 0 3401 6 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 1760 7 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 1182 6 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 4039 11 0 0
v_cancel_supplier_invoice 878 866 12 1.36 0 2846 21 0 12
v_cancel_supplier_order 822 822 0 0.00 0 300 3 0 0
v_cancel_write_off 1998 1998 0 0.00 0 5379 17 0 0
v_max_id_clo_ord 5480 5480 0 0.00 0 5570 2 0 0
v_max_id_clo_res 974 974 0 0.00 0 2639 17 0 0
v_max_id_ord_sup 5422 5422 0 0.00 0 4749 5 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 3160 5 0 0
v_max_non_paid_realizn 3843 3843 0 0.00 0 5528 6 0 0
v_min_id_clo_ord 5480 5480 0 0.00 0 8603 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 8277 19 0 0
v_min_id_ord_sup 5422 5422 0 0.00 0 6490 13 0 0
v_min_non_paid_invoice 5915 5915 0 0.00 0 6762 19 0 0
v_min_non_paid_realizn 7079 7079 0 0.00 0 12906 23 0 0
v_our_firm 10176 10176 0 0.00 0 14 1 0 0
v_reserve_write_off 19984 19984 0 0.00 0 3102 5 0 0
x_lock_depdocs_on_canc_invoice 11819 11819 0 0.00 0 13236 107 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 8046 307 0 0
x_make_qstorno_1000_1200 5482 5482 0 0.00 2 69038 1218 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 103964 3337 0 0
x_make_qstorno_2100_3300 452922 452922 0 0.00 0 53971 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2021 19:38:56 to 21-08-2021 19:39:00.

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
17:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:51 22587 8192000000 10369751664 10692935680 1421637184 14.787 100 84 167 1 1454745104 5833072 27274848
17:10:18 21661 8192000000 10436538176 10767839232 1491328576 15.400 100 90 180 1 1529137232 8236304 29572352
17:11:46 18358 8192000000 10484315216 10832396288 1548739632 15.899 100 81 162 0 1584101296 9806416 25555248
17:13:11 11648 8192000000 10584289712 10944958464 1643012384 16.705 100 85 168 2 1679628736 10865824 25750528
17:14:25 9217 8192000000 10636864464 11002273792 1698644768 17.174 100 80 160 0 1734484928 12472224 23367936
17:15:36 7370 8192000000 10704459200 11080368128 1760111152 17.685 100 79 158 0 1794875424 11877312 22886960
17:16:46 3463 8192000000 10752331968 11134332928 1804456416 18.050 100 84 169 1 1842751168 14825504 23469248
17:17:52 2120 8192000000 10796663184 11181658112 1851408336 18.434 100 92 185 3 1890709248 13460848 25840064
17:18:56 1649 8192000000 10816196640 11203198976 1871364128 18.595 100 91 182 1 1909706080 13116336 25225616
17:19:58 1739 8192000000 10822557952 11212894208 1875034208 18.625 100 90 182 1 1912150768 10805584 26310976
17:21:01 8660 8192000000 10781753824 11214725120 1879367424 18.660 100 21 41 1 1895914656 10631072 5916160
17:22:12 8888 8192000000 10794625136 11229528064 1890862544 18.753 100 15 30 0 1900304944 5567664 3874736
17:23:25 13680 8192000000 10802620368 11237011456 1895292368 18.788 100 28 55 3 1908163344 5310064 7560912
17:24:40 27919 8192000000 10814625584 11251834880 1900901904 18.834 100 26 53 3 1914181968 6060256 7219808
17:26:12 6446 8192000000 10813524192 11248537600 1902250272 18.844 100 22 44 3 1914621408 5376384 6994752
17:27:19 8679 8192000000 10800603744 11238731776 1885364160 18.708 99 30 60 0 1900646832 5469328 9813344
17:28:28 62444 8192000000 10754613664 11155406848 1741834080 17.534 94 35 71 2 1756565152 4765328 9965744
17:30:31 6107 8192000000 10702115280 11115384832 1763488752 17.713 97 27 53 3 1777010352 5778640 7742960
17:31:37 4804 8192000000 10733726064 11145695232 1814098176 18.129 100 37 75 1 1831994224 6519216 11376832
17:32:46 10029 8192000000 10746998400 11165003776 1830668864 18.265 100 30 58 2 1845424576 4852400 9903312
17:33:56 28419 8192000000 10753944496 11190861824 1854098976 18.455 100 35 69 3 1871048256 6317056 10632224
17:35:27 3998 8192000000 10779309008 11206856704 1864446688 18.539 100 23 46 3 1878367552 7096000 6824864
17:36:31 5389 8192000000 10787875168 11220602880 1877120384 18.642 100 27 54 0 1890951328 5840224 7990720
17:37:40 10726 8192000000 10825028192 11256156160 1909649216 18.904 100 14 27 0 1917885120 4423920 3811984
17:38:55 104311 8192000000 10869509504 11310088192 1943451072 19.174 100 40 81 2 1965536688 10194528 11891088
17:41:47 18537 8192000000 10914941744 11341574144 1972956848 19.409 100 63 122 0 2007305936 16519440 17829648
17:43:13 33100 8192000000 10898982544 11339096064 1938855696 19.138 98 34 68 2 1968374352 19338320 10180336
17:45:03 15786 8192000000 10941941152 11382968320 1994002672 19.575 100 37 74 2 2029481472 23916976 11561824
17:46:22 12231 8192000000 10953964832 11394674688 1999993840 19.623 100 50 99 5 2043666352 29236352 14436160
17:47:37 5691 8192000000 10968230736 11396227072 1970244880 19.387 98 60 120 0 2019610304 30821744 18543680
17:48:55 7792 8192000000 10978624224 11409121280 2009898192 19.701 100 66 130 1 2061930560 32995008 19037360
17:50:10 3270 8192000000 10972721568 11399114752 1989057488 19.536 99 69 140 1 2048090176 37951920 21080768
17:51:18 1885 8192000000 11010245776 11432124416 2026995456 19.835 100 91 180 5 2100198144 46721968 26480720
17:52:24 1709 8192000000 10981986048 11393511424 1983704080 19.494 99 89 177 2 2063452144 52388224 27359840
17:53:30 2463 8192000000 10980242384 11395141632 1977450928 19.445 100 85 169 2 2064002592 59722240 26829424
17:54:38 3317 8192000000 10950603936 11360075776 1953418096 19.254 100 88 174 3 2034181408 52925456 27837856
17:55:45 3981 8192000000 10971823904 11390087168 1972148560 19.402 100 77 152 3 2055972832 59389088 24435184
17:57:00 8895 8192000000 10951616144 11389132800 1976674096 19.438 100 62 124 0 2046770480 50846912 19249472
17:58:23 11008 8192000000 10939513264 11381043200 1971128160 19.394 100 43 86 2 2025261280 40403104 13730016
17:59:39 11434 8192000000 10940262176 11387691008 1968123008 19.371 100 32 64 1 2028476272 49737488 10615776
18:00:54 23870 8192000000 10908680112 11347808256 1919506704 18.983 99 29 58 1 1972915360 42853248 10555408
18:02:21 19007 8192000000 10895005584 11353415680 1900790320 18.833 98 27 54 0 1958229088 47437968 10000800
18:03:43 48145 8192000000 10847592752 11310059520 1868062784 18.569 97 15 30 2 1890173024 17944896 4165344
18:05:33 19350 8192000000 10860603248 11301548032 1923465408 19.015 100 41 80 4 1943680512 8723744 11491360
18:06:57 44871 8192000000 10864230176 11314524160 1916396160 18.958 99 30 61 1 1932800256 8411632 7992464
18:08:43 31236 8192000000 10844293888 11303968768 1908855072 18.897 99 6 12 1 1920391792 9955664 1581056
18:10:58 60543 8192000000 10870833376 11322097664 1943339472 19.173 100 20 40 0 1958910912 10008160 5563280
18:13:01 62366 8192000000 10887806368 11343441920 1957547888 19.287 100 16 32 2 1981826384 19543520 4734976
18:15:17 35265 8192000000 10897353648 11352625152 1946500400 19.199 99 26 52 1 1974963728 19874640 8588688
18:16:54 41395 8192000000 10889643744 11360411648 1961439712 19.317 100 13 26 0 1980504048 15203184 3861152
18:18:45 93678 8192000000 10917830848 11371237376 1979386000 19.460 100 22 43 2 1997919408 12103360 6430048
18:21:26 67138 8192000000 10919209216 11386527744 1982841248 19.487 100 11 22 3 1998065120 12272912 2950960
18:23:33 34709 8192000000 10965849552 11411877888 2012468688 19.721 100 35 68 2 2037240704 14674288 10097728
18:25:27 95411 8192000000 10917446400 11397558272 1969020336 19.378 98 13 27 1 1990696864 17954064 3722464
18:28:08 18380 8192000000 10934194928 11376418816 1974710560 19.423 99 35 71 2 1992614352 7511296 10392496
18:29:27 107577 8192000000 10939083376 11418615808 1965767200 19.352 98 22 44 4 1996885840 24315424 6803216
18:32:27 92556 8192000000 10923144768 11398733824 1980694832 19.470 99 30 60 1 2006485344 17737568 8052944
18:35:14 5024 8192000000 10936109152 11394973696 1997376304 19.602 100 33 67 3 2017118896 9911296 9831296
18:36:26 40969 8192000000 10889898144 11368161280 1950002256 19.226 98 21 42 0 1964745520 9044848 5698416
18:38:08 38227 8192000000 10880912528 11344465920 1943145072 19.172 98 10 19 1 1953508048 7409184 2953792
18:39:54 30899 8192000000 10854463824 11329179648 1910257600 18.909 98 13 26 1 1922631488 9151424 3222464
18:41:33 54041 8192000000 10886290336 11350687744 1930251504 19.069 98 13 26 0 1942180672 8429136 3500032
18:43:36 35369 8192000000 10861470720 11326038016 1888002752 18.730 96 18 36 0 1899662880 7028864 4631264
18:45:12 29113 8192000000 10835540224 11290300416 1888088416 18.730 99 28 56 1 1908211232 11515664 8607152
18:46:42 9674 8192000000 10891650384 11331325952 1948886256 19.218 100 60 120 2 1974646144 8499488 17260400
18:47:55 26386 8192000000 10883724112 11337576448 1946276640 19.197 100 32 64 0 1965533888 10407328 8849920
18:49:24 33621 8192000000 10876605856 11328212992 1905791728 18.873 98 33 66 2 1924359792 9747440 8820624
18:51:25 75125 8192000000 10870810720 11347292160 1910523744 18.911 98 18 36 0 1939441360 23770144 5147472
18:53:41 45510 8192000000 10893145056 11323113472 1890778800 18.752 97 31 62 1 1907479792 8285056 8415936
18:55:28 73662 8192000000 10872011040 11342249984 1917219088 18.965 98 16 32 1 1933961360 12031040 4711232
18:57:46 111487 8192000000 10871483728 11331878912 1874980592 18.625 96 28 56 1 1891742976 8688880 8073504
19:00:41 34053 8192000000 10869159984 11339366400 1946528672 19.199 100 39 78 2 1972670640 14789472 11352496
19:02:24 61343 8192000000 10863845584 11346006016 1926656672 19.040 99 9 18 0 1946666592 17471056 2538864
19:05:00 52327 8192000000 10853711968 11330740224 1917604416 18.968 99 12 24 0 1930941472 9791744 3545312
19:07:34 50907 8192000000 10873287536 11330748416 1878014704 18.649 96 27 54 0 1895762256 9960912 7786640
19:09:38 54151 8192000000 10911044512 11375824896 1955041104 19.267 99 20 40 0 1972222112 11729792 5451216
19:11:33 78356 8192000000 10921263504 11407839232 1985250160 19.506 100 12 24 0 2006137344 17716368 3170816
19:14:08 52242 8192000000 10936280208 11394994176 1992217168 19.561 100 27 56 1 2009279968 8977872 8084928
19:16:25 39248 8192000000 10929309264 11388153856 1963785680 19.336 99 33 66 0 1991438512 18199728 9453104
19:18:12 62052 8192000000 10927633760 11395444736 1960346992 19.309 99 12 24 1 1976578080 12743120 3487968
19:20:24 127534 8192000000 10909535008 11369398272 1944793520 19.185 98 17 34 0 1958512976 9058592 4660864
19:23:37 78702 8192000000 10898356240 11387478016 1965336928 19.348 99 8 16 0 1982924656 15250368 2337360
19:25:56 75726 8192000000 10871691952 11340677120 1856497952 18.475 94 16 32 2 1878408144 17385728 4524464
19:28:18 67465 8192000000 10888822720 11372933120 1939598752 19.144 99 13 26 0 1962093184 18331760 4162672
19:30:29 47940 8192000000 10870910112 11336122368 1893128896 18.771 97 15 30 1 1908681968 10966720 4586352
19:32:35 70154 8192000000 10851109824 11326472192 1881814272 18.680 97 24 48 0 1899374416 10487952 7072192
19:34:51 29605 8192000000 10840169264 11298189312 1836792048 18.315 94 27 54 0 1852197904 8239872 7165984
19:36:30 26817 8192000000 10862314272 11318095872 1921073344 18.995 100 34 68 0 1943974768 12968848 9932576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-08-2021 19:39:02 to 21-08-2021 19:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 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 21-08-2021 19:39:17 to 21-08-2021 19:39:19.

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

21.08.21 19:39:20. 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-08-21 19:39:23 No severe PSQL-related problems occured
21.08.21 19:39:24. End of report.