Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.09.2021 16: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 16153740 kB
MemAvailable 30772720 kB
Buffers 0 kB
Cached 15511120 kB
SwapCached 668 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-20 16:37:50
DTS_END                         2021-09-20 16:37:50.2070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33318
TRN_ID                          239832
ATT_ID                          719
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: 9076.83 21498 1089220
customer order: creation 175.94 342 21113
customer order: refuse 14.63 367 1755
order to supplier: creation 44.08 1292 5289
order to supplier: removal 3.38 109524 406
invoice (draft): creation 46.73 3082 5608
invoice (draft): removal 53.19 732 6383
invoice accept: apply 40.50 19272 4860
invoice accept: cancel 47.50 788 5700
customer reserve: creation 3718.56 192 446227
customer reserve: removal 4631.86 64 555823
realization accept: apply 85.33 203 10239
realization accept: cancel 85.43 125 10252
payment from customer: creation 58.13 140 6975
payment from customer: removal 5.70 92 684
payment to supplier: creation 49.94 179 5993
payment to supplier: removal 3.47 95 416
service: total inventory turnovers 5.00 1058 600
service: total monetary turnovers 6.61 21 793
service: aggregate perf. data 0.84 18927 101
service: refresh index statistics 0.03 273459 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-09-2021 16:38:27 to 20-09-2021 16:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1605
TEST_TIME 2 2465
TEST_TIME 3 3040
TEST_TIME 4 3608
TEST_TIME 5 3826
TEST_TIME 6 4276
TEST_TIME 7 4350
TEST_TIME 8 4337
TEST_TIME 9 4305
TEST_TIME 10 4277
TEST_TIME 11 4637
TEST_TIME 12 5062
TEST_TIME 13 5371
TEST_TIME 14 5574
TEST_TIME 15 6078
TEST_TIME 16 6389
TEST_TIME 17 6505
TEST_TIME 18 6498
TEST_TIME 19 6706
TEST_TIME 20 7304
TEST_TIME 21 7262
TEST_TIME 22 7795
TEST_TIME 23 7746
TEST_TIME 24 7840
TEST_TIME 25 8168
TEST_TIME 26 9051
TEST_TIME 27 9313
TEST_TIME 28 9963
TEST_TIME 29 11155
TEST_TIME 30 10991
TEST_TIME 31 10748
TEST_TIME 32 10558
TEST_TIME 33 10475
TEST_TIME 34 10305
TEST_TIME 35 10235
TEST_TIME 36 10205
TEST_TIME 37 10121
TEST_TIME 38 9973
TEST_TIME 39 9876
TEST_TIME 40 9781
TEST_TIME 41 9840
TEST_TIME 42 9781
TEST_TIME 43 9609
TEST_TIME 44 9560
TEST_TIME 45 9585
TEST_TIME 46 9590
TEST_TIME 47 9426
TEST_TIME 48 9364
TEST_TIME 49 9359
TEST_TIME 50 9289
TEST_TIME 51 9438
TEST_TIME 52 9349
TEST_TIME 53 9215
TEST_TIME 54 9196
TEST_TIME 55 9087
TEST_TIME 56 9215
TEST_TIME 57 9169
TEST_TIME 58 9199
TEST_TIME 59 9181
TEST_TIME 60 9205
TEST_TIME 61 9169
TEST_TIME 62 9247
TEST_TIME 63 9237
TEST_TIME 64 9382
TEST_TIME 65 9388
TEST_TIME 66 9364
TEST_TIME 67 9391
TEST_TIME 68 9422
TEST_TIME 69 9373
TEST_TIME 70 9392
TEST_TIME 71 9290
TEST_TIME 72 9277
TEST_TIME 73 9218
TEST_TIME 74 9220
TEST_TIME 75 9171
TEST_TIME 76 9153
TEST_TIME 77 9103
TEST_TIME 78 9152
TEST_TIME 79 9084
TEST_TIME 80 9052
TEST_TIME 81 9052
TEST_TIME 82 9095
TEST_TIME 83 9126
TEST_TIME 84 9122
TEST_TIME 85 9135
TEST_TIME 86 9152
TEST_TIME 87 9168
TEST_TIME 88 9157
TEST_TIME 89 9158
TEST_TIME 90 9229
TEST_TIME 91 9228
TEST_TIME 92 9287
TEST_TIME 93 9287
TEST_TIME 94 9326
TEST_TIME 95 9350
TEST_TIME 96 9286
TEST_TIME 97 9271
TEST_TIME 98 9218
TEST_TIME 99 9205
TEST_TIME 100 9183
TEST_TIME 101 9158
TEST_TIME 102 9135
TEST_TIME 103 9125
TEST_TIME 104 9073
TEST_TIME 105 9046
TEST_TIME 106 9023
TEST_TIME 107 9016
TEST_TIME 108 9001
TEST_TIME 109 8987
TEST_TIME 110 8997
TEST_TIME 111 9005
TEST_TIME 112 9031
TEST_TIME 113 9043
TEST_TIME 114 9058
TEST_TIME 115 9080
TEST_TIME 116 9070
TEST_TIME 117 9102
TEST_TIME 118 9135
TEST_TIME 119 9114
TEST_TIME 120 9077

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-09-2021 16:38:31 to 20-09-2021 16:38:34.

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 1502820 1502820 0 0.00 0 25053 7 0 0
doc_list_biud 1502819 1502819 0 0.00 0 67968 29 0 0
sp_add_invoice_to_stock 4973 4860 113 2.27 51 172375 19273 0 113
sp_cancel_adding_invoice 5700 5700 0 0.00 4 72364 789 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 13002 367 0 0
sp_cancel_customer_reserve 555823 555823 0 0.00 1 67969 64 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 2 10890 92 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 5911 95 0 0
sp_cancel_supplier_invoice 6388 6383 5 0.07 2 35003 732 0 5
sp_cancel_supplier_order 406 406 0 0.00 7 1179408 109525 0 0
sp_cancel_write_off 10252 10252 0 0.00 1 13986 126 0 0
sp_client_order 21113 21113 0 0.00 3 54840 342 0 0
sp_customer_reserve 446227 446227 0 0.00 0 124246 193 0 0
sp_fill_shopping_cart 32001 32001 0 0.00 1 20768 41 0 0
sp_fill_shopping_cart_clo_res 446226 446226 0 0.00 0 21490 12 0 0
sp_get_clo_for_invoice 4859 4859 0 0.00 0 19503 299 0 0
sp_kill_cost_storno 17052 17052 0 0.00 0 13984 59 0 0
sp_kill_qstorno_ret_qs2qd 1128687 1128687 0 0.00 0 67364 34 0 0
sp_kill_qty_storno 595416 595416 0 0.00 0 67968 73 0 0
sp_lock_selected_doc 20808 20808 0 0.00 0 5932 5 0 0
sp_make_cost_storno 28066 28066 0 0.00 0 24978 63 0 0
sp_multiply_rows_for_pdistr 28065 28065 0 0.00 0 13130 35 0 0
sp_multiply_rows_for_qdistr 21113 21113 0 0.00 0 54825 141 0 0
sp_pay_from_customer 6975 6975 0 0.00 4 16931 140 0 0
sp_pay_to_supplier 5993 5993 0 0.00 4 21396 180 0 0
sp_payment_common 12968 12968 0 0.00 1 12475 42 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 10851 327 0 0
sp_qd_handle_on_invoice_upd_sts 10558 10558 0 0.00 0 17339 312 0 0
sp_qd_handle_on_reserve_upd_sts 10239 10239 0 0.00 0 13109 65 0 0
sp_reserve_write_off 10239 10239 0 0.00 6 15173 203 0 0
sp_supplier_invoice 5608 5608 0 0.00 6 91171 3083 0 0
sp_supplier_order 5289 5289 0 0.00 5 35692 1293 0 0
srv_aggregate_perf_data 101 101 0 0.00 2629 150472 18928 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1728 114254 46054 0 0
srv_find_qd_qs_mism 1042088 1042088 0 0.00 0 13571 6 0 0
srv_make_invnt_saldo 600 600 0 0.00 32 133272 1058 0 0
srv_make_money_saldo 793 793 0 0.00 0 599 22 0 0
srv_recalc_idx_stat 3 3 0 0.00 210651 324611 273459 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4860 4860 0 0.00 0 27332 179 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 4734 107 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 1586 12 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1033 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 2125 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 149 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1314 15 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21757 1677 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 1981 6 0 0
t$perf-norm:sp_client_order 21113 21113 0 0.00 0 17042 17 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 3175 8 0 0
t$perf-norm:sp_pay_from_customer 6975 6975 0 0.00 0 20908 12 0 0
t$perf-norm:sp_pay_to_supplier 5993 5993 0 0.00 0 6530 8 0 0
t$perf-norm:sp_reserve_write_off 10239 10239 0 0.00 0 15214 11 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 7932 18 0 0
t$perf-norm:sp_supplier_order 5289 5289 0 0.00 0 20754 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 560 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 51 2 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 849 15 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 892 14 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9942 9716 226 2.27 0 14234 25 0 226
v_all_customers 38044 38044 0 0.00 0 237 1 0 0
v_all_suppliers 30710 30710 0 0.00 0 55 1 0 0
v_all_wares 42226 42226 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1557 8 0 0
v_cancel_client_order 3510 3510 0 0.00 0 11178 13 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 10881 15 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 4343 10 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 433 4 0 0
v_cancel_supplier_invoice 810 800 10 1.23 0 596 12 0 10
v_cancel_supplier_order 812 812 0 0.00 0 12224 18 0 0
v_cancel_write_off 1988 1988 0 0.00 0 12104 30 0 0
v_max_id_clo_ord 5287 5287 0 0.00 0 3190 3 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2239 13 0 0
v_max_id_ord_sup 5600 5600 0 0.00 0 5027 4 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 8974 10 0 0
v_max_non_paid_realizn 3870 3870 0 0.00 0 3474 6 0 0
v_min_id_clo_ord 5287 5287 0 0.00 0 13629 14 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 6592 12 0 0
v_min_id_ord_sup 5600 5600 0 0.00 0 6201 11 0 0
v_min_non_paid_invoice 5993 5993 0 0.00 0 9444 30 0 0
v_min_non_paid_realizn 6975 6975 0 0.00 0 8951 26 0 0
v_our_firm 10392 10392 0 0.00 0 16 1 0 0
v_reserve_write_off 20478 20478 0 0.00 0 14237 11 0 0
x_lock_depdocs_on_canc_invoice 11683 11683 0 0.00 0 18004 121 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 11731 471 0 0
x_make_qstorno_1000_1200 5289 5289 0 0.00 2 35233 1210 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 3 88633 2937 0 0
x_make_qstorno_2100_3300 446227 446227 0 0.00 0 124244 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-09-2021 16:38:35 to 20-09-2021 16:38:39.

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:07:53 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:54 26648 8192000000 10369574272 10686455808 1421052480 14.782 100 90 180 1 1455779040 5524512 29202048
14:10:28 30914 8192000000 10438924640 10768494592 1486748256 15.360 100 87 174 0 1522336064 7578464 28009344
14:12:11 22464 8192000000 10521821008 10865438720 1569315680 16.076 100 82 164 1 1604366032 9850176 25200176
14:13:51 12460 8192000000 10610823808 10974121984 1669265840 16.927 100 82 164 1 1705458960 12415040 23778080
14:15:06 6791 8192000000 10663718416 11038625792 1728902304 17.426 100 76 152 1 1763290096 12142400 22245392
14:16:14 4520 8192000000 10729206336 11106717696 1782863392 17.873 100 82 165 3 1818339680 12255184 23221104
14:17:21 4066 8192000000 10764370032 11147636736 1825823552 18.225 100 88 175 1 1863074320 12420896 24829872
14:18:27 1758 8192000000 10809190224 11194953728 1861657856 18.517 100 92 184 1 1901458560 13844976 25955728
14:19:30 32656 8192000000 10777954400 11205099520 1876226288 18.635 100 39 78 0 1895776592 7983392 11566912
14:21:23 9271 8192000000 10797058368 11223408640 1882849728 18.688 100 20 40 0 1895032480 7011296 5171456
14:22:33 19744 8192000000 10798739648 11236896768 1891901792 18.761 100 13 26 2 1900400480 5312640 3186048
14:23:53 7791 8192000000 10810897088 11247665152 1905088992 18.867 100 25 49 0 1918335776 6819200 6427584
14:25:05 53906 8192000000 10819170544 11254325248 1892043184 18.762 99 29 57 1 1906173072 6196160 7933728
14:27:01 4762 8192000000 10823127552 11266351104 1914703840 18.944 100 26 51 0 1931917424 10041392 7172192
14:28:07 3153 8192000000 10815916704 11238727680 1890061072 18.746 100 38 76 1 1907222256 6347664 10813520
14:29:10 8321 8192000000 10711696416 11127255040 1667410576 16.911 90 12 24 1 1675474400 4968288 3095536
14:30:23 29971 8192000000 10706851760 11123666944 1731807968 17.451 95 30 60 3 1746581328 6096144 8677216
14:31:55 5899 8192000000 10707577248 11124649984 1780802512 17.856 99 24 46 3 1793181248 5928032 6450704
14:33:02 7747 8192000000 10733838512 11155628032 1794374176 17.968 98 27 53 2 1808028016 5606928 8046912
14:34:11 18549 8192000000 10776091920 11192926208 1836939424 18.316 99 21 42 2 1847823072 5351056 5532592
14:35:30 15845 8192000000 10769645088 11204108288 1861415216 18.515 100 18 34 1 1872288976 5412656 5461104
14:36:46 10461 8192000000 10810486784 11232919552 1879907504 18.664 100 21 42 2 1891653296 5404432 6341360
14:38:02 25874 8192000000 10847122832 11267878912 1915225200 18.949 100 41 82 1 1934204304 5581904 13397200
14:39:34 12927 8192000000 10866009552 11291402240 1934096080 19.100 100 42 84 0 1957787040 11497824 12193136
14:40:59 67794 8192000000 10879792800 11321630720 1951165520 19.236 100 41 80 3 1976454512 13329728 11959264
14:43:55 10096 8192000000 10915625280 11348672512 1977669648 19.446 100 50 100 0 2007912736 15215040 15028048
14:45:14 12500 8192000000 10918453104 11344953344 1962062672 19.322 99 55 109 1 1995551408 17530464 15958272
14:46:38 7365 8192000000 10930950816 11369193472 1987508688 19.524 100 51 102 0 2025998000 23789984 14699328
14:48:12 3132 8192000000 10962225536 11391950848 2004441136 19.658 100 67 133 1 2048225936 24085840 19698960
14:49:19 23443 8192000000 10954986992 11390722048 1998539984 19.611 100 55 110 0 2046333200 30439504 17353712
14:50:57 6658 8192000000 10970550928 11399340032 2004163712 19.656 100 69 138 0 2059372784 34402384 20806688
14:52:08 3025 8192000000 10964099536 11380305920 1978822704 19.455 100 81 161 1 2048992784 45461824 24708256
14:53:15 5429 8192000000 10988911792 11410817024 2001729328 19.636 100 84 169 2 2074277712 46483744 26064640
14:54:28 3718 8192000000 10940172800 11352420352 1938056128 19.131 99 75 143 0 2004692288 44405968 22230192
14:55:36 4984 8192000000 10982850544 11391377408 1980091792 19.465 100 91 179 0 2061470352 54092976 27285584
14:56:46 1727 8192000000 11003391888 11415126016 1996538624 19.595 100 86 171 1 2079479856 56163488 26777744
14:57:52 9254 8192000000 10966416560 11400265728 1980493872 19.469 100 47 94 0 2050104192 53646976 15963344
14:59:07 19525 8192000000 10950064656 11394576384 1969710944 19.383 100 35 70 2 2033877104 51840848 12325312
15:00:35 10269 8192000000 10957767328 11387920384 1953137904 19.251 99 52 104 0 2029287168 59066368 17082896
15:01:57 16588 8192000000 10959153296 11411238912 1963489440 19.334 100 40 80 1 2048103168 70734848 13878880
15:03:22 12024 8192000000 10949625248 11405049856 1941403264 19.158 99 28 56 3 2021024256 69714208 9906784
15:04:59 25228 8192000000 10923169616 11388198912 1906612880 18.879 98 21 42 0 1970128528 55449008 8066640
15:06:34 49626 8192000000 10896097920 11348205568 1926503552 19.039 99 7 14 1 1948698688 19858880 2336256
15:08:25 26542 8192000000 10878229088 11320631296 1904006048 18.859 97 39 78 1 1923872352 9602800 10263504
15:09:55 42929 8192000000 10878091872 11335606272 1947893472 19.210 100 26 52 0 1968521952 13078080 7550400
15:11:50 112632 8192000000 10885661072 11365441536 1925790896 19.033 98 16 31 0 1953630112 23810176 4029040
15:14:45 39324 8192000000 10882907120 11352432640 1943392448 19.174 99 10 20 0 1954511696 7647104 3472144
15:16:26 59548 8192000000 10898966000 11370405888 1957904560 19.289 99 12 24 0 1973543168 12417328 3221280
15:18:28 63610 8192000000 10884146816 11357290496 1932534704 19.087 98 15 32 1 1947718800 10623776 4560320
15:20:32 49535 8192000000 10902439136 11351863296 1933170048 19.092 98 42 84 0 1953504704 8597664 11736992
15:22:27 97478 8192000000 10893868592 11372683264 1978043872 19.449 100 27 54 1 1995449904 9853008 7553024
15:25:05 7240 8192000000 10930987600 11387076608 1995382784 19.586 100 44 88 2 2017628400 8340320 13905296
15:26:13 29418 8192000000 10918022144 11385131008 1993238384 19.569 100 28 57 1 2010137808 9171648 7727776
15:27:48 36782 8192000000 10935704368 11392385024 1996117744 19.592 100 37 74 2 2019370976 12020640 11232592
15:29:36 103574 8192000000 10903999760 11380432896 1975923152 19.432 99 11 22 0 1986868528 7777632 3167744
15:32:20 51429 8192000000 10924858864 11398033408 1992786432 19.566 100 12 24 1 2011725296 15295744 3643120
15:34:19 41356 8192000000 10908591184 11367084032 1942982320 19.171 98 31 62 1 1963739296 11929680 8827296
15:36:01 51254 8192000000 10902669024 11367612416 1928026576 19.051 97 19 38 0 1941154032 7760544 5366912
15:37:53 66132 8192000000 10903748880 11364950016 1899531008 18.823 96 31 62 1 1920199232 12270384 8397840
15:41:03 53135 8192000000 10896522160 11356205056 1921485232 18.999 98 19 38 0 1941253392 14463584 5304576
15:42:58 35024 8192000000 10891652896 11341123584 1932686224 19.088 99 36 72 0 1958077248 15639712 9751312
15:44:34 94644 8192000000 10882695600 11335602176 1890961312 18.754 97 14 28 1 1905176640 9891088 4324240
15:47:11 20945 8192000000 10884636224 11338940416 1941908288 19.162 100 51 102 2 1970237952 14514816 13814848
15:48:32 103588 8192000000 10856281168 11302187008 1868721456 18.574 96 27 54 3 1887982976 11152240 8109280
15:52:01 57270 8192000000 10835863344 11310403584 1877742480 18.647 97 9 18 1 1892283488 11853008 2688000
15:53:59 50262 8192000000 10824214896 11292524544 1885551184 18.710 98 14 29 0 1902066192 12430096 4084912
15:55:51 35933 8192000000 10870858336 11327983616 1934128592 19.100 100 15 30 2 1946855424 8326352 4400480
15:57:31 50959 8192000000 10832259888 11308015616 1888091584 18.730 97 19 38 0 1907030064 13282768 5655712
16:00:04 62389 8192000000 10871645680 11330789376 1871085408 18.593 96 16 32 0 1885138144 9959008 4093728
16:02:10 91123 8192000000 10888426656 11352027136 1934467184 19.103 99 25 50 0 1952901120 11157568 7276368
16:04:41 53964 8192000000 10870121520 11343339520 1920132848 18.988 98 23 46 3 1938779264 12196096 6450320
16:06:36 58441 8192000000 10895675216 11361808384 1956934096 19.282 100 22 44 0 1978461680 15571904 5955680
16:08:35 114254 8192000000 10888142336 11371126784 1919368352 18.982 97 12 23 1 1933650368 11725664 2556352
16:11:29 94929 8192000000 10912957232 11386519552 1972225856 19.403 100 9 18 0 1996397968 21389648 2782464
16:14:04 86259 8192000000 10906794032 11381387264 1948746256 19.216 98 22 43 1 1964062304 8906944 6409104
16:17:50 76587 8192000000 10902003248 11394023424 1978685456 19.454 100 10 20 2 1994903536 13416752 2801328
16:20:07 56456 8192000000 10867159632 11343351808 1886583184 18.718 95 16 32 1 1901993456 10638656 4771616
16:22:04 106465 8192000000 10880560880 11371786240 1930092960 19.068 98 21 42 0 1949310688 13474016 5743712
16:25:13 87905 8192000000 10892001536 11367649280 1894697808 18.784 96 12 24 0 1909485776 11311568 3476400
16:27:41 44953 8192000000 10890960288 11358396416 1920691600 18.992 97 26 52 1 1940208496 12531648 6985248
16:29:31 71417 8192000000 10902375776 11374067712 1940671888 19.152 98 18 36 2 1956066400 10074896 5319616
16:31:51 51101 8192000000 10862991168 11329597440 1910655632 18.912 98 28 56 0 1930850208 12459088 7735488
16:33:43 57899 8192000000 10895191024 11380596736 1968623280 19.375 100 24 46 3 1987688256 12536560 6528416
16:35:43 64078 8192000000 10874634176 11352346624 1855456448 18.466 94 27 54 1 1877949184 14886384 7606352
16:37:47 11354 8192000000 9055296800 9264050176 219593440 2.610 12 1 2 0 220827584 1172016 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 20-09-2021 16:38:41 to 20-09-2021 16:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-09-2021 16:38:57 to 20-09-2021 16:38:59.

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

20.09.21 16:39:00. 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-20 16:39:02 No severe PSQL-related problems occured
20.09.21 16:39:03. End of report.