Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.08.2021 01:37
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 16492128 kB
MemAvailable 31012268 kB
Buffers 0 kB
Cached 15149964 kB
SwapCached 2580 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-05 01:37:42
DTS_END                         2021-08-05 01:37:42.0530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60908
TRN_ID                          238264
ATT_ID                          665
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: 9023.98 18806 1082877
customer order: creation 169.43 325 20331
customer order: refuse 14.49 352 1739
order to supplier: creation 45.93 1204 5512
order to supplier: removal 3.40 103167 408
invoice (draft): creation 45.38 3423 5446
invoice (draft): removal 50.95 767 6114
invoice accept: apply 42.22 18642 5066
invoice accept: cancel 45.89 752 5507
customer reserve: creation 3865.88 187 463905
customer reserve: removal 4441.40 62 532968
realization accept: apply 83.98 194 10078
realization accept: cancel 86.43 104 10372
payment from customer: creation 57.83 124 6940
payment from customer: removal 5.83 59 699
payment to supplier: creation 49.75 149 5970
payment to supplier: removal 3.43 50 411
service: total inventory turnovers 4.67 1073 560
service: total monetary turnovers 6.31 21 757
service: aggregate perf. data 0.75 26022 90
service: refresh index statistics 0.03 219451 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 01:38:18 to 05-08-2021 01:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3002
TEST_TIME 2 2524
TEST_TIME 3 2738
TEST_TIME 4 3500
TEST_TIME 5 4547
TEST_TIME 6 4501
TEST_TIME 7 4537
TEST_TIME 8 4972
TEST_TIME 9 5465
TEST_TIME 10 5657
TEST_TIME 11 5972
TEST_TIME 12 5915
TEST_TIME 13 6194
TEST_TIME 14 6149
TEST_TIME 15 6359
TEST_TIME 16 6459
TEST_TIME 17 6456
TEST_TIME 18 6774
TEST_TIME 19 7116
TEST_TIME 20 7627
TEST_TIME 21 7900
TEST_TIME 22 8203
TEST_TIME 23 8576
TEST_TIME 24 8423
TEST_TIME 25 8447
TEST_TIME 26 8688
TEST_TIME 27 9143
TEST_TIME 28 9995
TEST_TIME 29 10648
TEST_TIME 30 10531
TEST_TIME 31 10381
TEST_TIME 32 10219
TEST_TIME 33 10025
TEST_TIME 34 9810
TEST_TIME 35 9766
TEST_TIME 36 9660
TEST_TIME 37 9549
TEST_TIME 38 9641
TEST_TIME 39 9493
TEST_TIME 40 9330
TEST_TIME 41 9427
TEST_TIME 42 9446
TEST_TIME 43 9391
TEST_TIME 44 9286
TEST_TIME 45 9145
TEST_TIME 46 9090
TEST_TIME 47 9170
TEST_TIME 48 9135
TEST_TIME 49 9199
TEST_TIME 50 9096
TEST_TIME 51 9062
TEST_TIME 52 8932
TEST_TIME 53 8967
TEST_TIME 54 8886
TEST_TIME 55 8845
TEST_TIME 56 8798
TEST_TIME 57 8797
TEST_TIME 58 8908
TEST_TIME 59 8886
TEST_TIME 60 8906
TEST_TIME 61 8898
TEST_TIME 62 8965
TEST_TIME 63 9018
TEST_TIME 64 9043
TEST_TIME 65 9172
TEST_TIME 66 9135
TEST_TIME 67 9219
TEST_TIME 68 9176
TEST_TIME 69 9128
TEST_TIME 70 9439
TEST_TIME 71 9387
TEST_TIME 72 9328
TEST_TIME 73 9225
TEST_TIME 74 9233
TEST_TIME 75 9154
TEST_TIME 76 9164
TEST_TIME 77 9116
TEST_TIME 78 9096
TEST_TIME 79 9048
TEST_TIME 80 9013
TEST_TIME 81 8974
TEST_TIME 82 8927
TEST_TIME 83 9011
TEST_TIME 84 8995
TEST_TIME 85 8974
TEST_TIME 86 9002
TEST_TIME 87 9031
TEST_TIME 88 9036
TEST_TIME 89 9066
TEST_TIME 90 9134
TEST_TIME 91 9119
TEST_TIME 92 9116
TEST_TIME 93 9123
TEST_TIME 94 9156
TEST_TIME 95 9196
TEST_TIME 96 9198
TEST_TIME 97 9188
TEST_TIME 98 9277
TEST_TIME 99 9223
TEST_TIME 100 9145
TEST_TIME 101 9125
TEST_TIME 102 9080
TEST_TIME 103 9057
TEST_TIME 104 9011
TEST_TIME 105 9016
TEST_TIME 106 9011
TEST_TIME 107 8990
TEST_TIME 108 8958
TEST_TIME 109 8945
TEST_TIME 110 8939
TEST_TIME 111 8956
TEST_TIME 112 8955
TEST_TIME 113 8941
TEST_TIME 114 8968
TEST_TIME 115 9026
TEST_TIME 116 9019
TEST_TIME 117 9030
TEST_TIME 118 9027
TEST_TIME 119 9018
TEST_TIME 120 9027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-08-2021 01:38:22 to 05-08-2021 01:38:25.

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 1514987 1514987 0 0.00 0 26951 7 0 0
doc_list_biud 1514986 1514986 0 0.00 0 20723 27 0 0
sp_add_invoice_to_stock 5214 5066 148 2.83 23 180546 18642 0 148
sp_cancel_adding_invoice 5507 5507 0 0.00 4 59185 753 0 0
sp_cancel_client_order 1739 1739 0 0.00 4 11263 352 0 0
sp_cancel_customer_reserve 532968 532968 0 0.00 1 18673 62 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 3276 60 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 2523 50 0 0
sp_cancel_supplier_invoice 6123 6114 9 0.14 2 15296 767 0 9
sp_cancel_supplier_order 408 408 0 0.00 9 1242103 103167 0 0
sp_cancel_write_off 10372 10372 0 0.00 1 8392 105 0 0
sp_client_order 20331 20331 0 0.00 4 22637 325 0 0
sp_customer_reserve 463905 463905 0 0.00 0 122586 188 0 0
sp_fill_shopping_cart 31288 31288 0 0.00 1 24022 42 0 0
sp_fill_shopping_cart_clo_res 463900 463900 0 0.00 0 26625 15 0 0
sp_get_clo_for_invoice 5057 5057 0 0.00 0 38112 327 0 0
sp_kill_cost_storno 16989 16989 0 0.00 0 7517 54 0 0
sp_kill_qstorno_ret_qs2qd 1082830 1082830 0 0.00 0 18667 33 0 0
sp_kill_qty_storno 572243 572243 0 0.00 0 20715 72 0 0
sp_lock_selected_doc 20840 20840 0 0.00 0 7869 5 0 0
sp_make_cost_storno 28045 28045 0 0.00 0 26836 67 0 0
sp_multiply_rows_for_pdistr 28045 28045 0 0.00 0 9285 30 0 0
sp_multiply_rows_for_qdistr 20331 20331 0 0.00 0 17478 135 0 0
sp_pay_from_customer 6940 6940 0 0.00 4 26307 124 0 0
sp_pay_to_supplier 5970 5970 0 0.00 5 25109 150 0 0
sp_payment_common 12910 12910 0 0.00 1 9045 32 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 11256 328 0 0
sp_qd_handle_on_invoice_upd_sts 10564 10564 0 0.00 0 20708 309 0 0
sp_qd_handle_on_reserve_upd_sts 10078 10078 0 0.00 0 16200 63 0 0
sp_reserve_write_off 10078 10078 0 0.00 7 26985 195 0 0
sp_supplier_invoice 5446 5446 0 0.00 7 95682 3423 0 0
sp_supplier_order 5512 5512 0 0.00 4 33302 1205 0 0
srv_aggregate_perf_data 90 90 0 0.00 2946 312348 26022 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1919 150519 48060 0 0
srv_find_qd_qs_mism 1036607 1036607 0 0.00 0 13008 6 0 0
srv_make_invnt_saldo 560 560 0 0.00 34 42612 1074 0 0
srv_make_money_saldo 757 757 0 0.00 1 618 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 127675 382978 219452 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5066 5066 0 0.00 0 10098 162 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 9308 109 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 1684 12 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 3780 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2708 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 7724 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 849 22 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 23930 1551 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 2840 8 0 0
t$perf-norm:sp_client_order 20331 20331 0 0.00 0 13376 16 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 767 4 0 0
t$perf-norm:sp_pay_from_customer 6940 6940 0 0.00 0 6579 9 0 0
t$perf-norm:sp_pay_to_supplier 5970 5970 0 0.00 0 9990 15 0 0
t$perf-norm:sp_reserve_write_off 10078 10078 0 0.00 0 8160 17 0 0
t$perf-norm:sp_supplier_invoice 5446 5446 0 0.00 0 7871 17 0 0
t$perf-norm:sp_supplier_order 5512 5512 0 0.00 0 7564 15 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 151 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 52 2 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 4027 29 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 6710 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10408 10112 296 2.84 0 6321 18 0 296
v_all_customers 37190 37190 0 0.00 0 20 1 0 0
v_all_suppliers 30532 30532 0 0.00 0 21 1 0 0
v_all_wares 40662 40662 0 0.00 0 89 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 514 6 0 0
v_cancel_client_order 3478 3478 0 0.00 0 2059 5 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 509 4 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 573 3 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 240 3 0 0
v_cancel_supplier_invoice 778 760 18 2.31 0 2446 17 0 18
v_cancel_supplier_order 816 816 0 0.00 0 670 4 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2576 12 0 0
v_max_id_clo_ord 5512 5512 0 0.00 0 1028 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 2279 14 0 0
v_max_id_ord_sup 5445 5445 0 0.00 0 11832 8 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 5659 6 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 1095 4 0 0
v_min_id_clo_ord 5512 5512 0 0.00 0 3159 6 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 5366 12 0 0
v_min_id_ord_sup 5445 5445 0 0.00 0 5224 9 0 0
v_min_non_paid_invoice 5970 5970 0 0.00 0 6353 23 0 0
v_min_non_paid_realizn 6940 6940 0 0.00 0 8006 21 0 0
v_our_firm 10018 10018 0 0.00 0 74 1 0 0
v_reserve_write_off 20156 20156 0 0.00 0 4576 5 0 0
x_lock_depdocs_on_canc_invoice 11241 11241 0 0.00 0 12677 124 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 5239 322 0 0
x_make_qstorno_1000_1200 5512 5512 0 0.00 1 33249 1121 0 0
x_make_qstorno_1200_2000 5446 5446 0 0.00 4 94556 3268 0 0
x_make_qstorno_2100_3300 463905 463905 0 0.00 0 122575 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 01:38:26 to 05-08-2021 01:38:30.

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:45 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:46 2612 8192000000 10337256752 10648653824 1398455200 14.581 100 90 181 0 1433256336 5256544 29544592
23:09:54 18938 8192000000 10405748032 10727038976 1457760864 15.106 100 91 183 0 1495107488 7205872 30140752
23:11:23 9115 8192000000 10461906880 10798563328 1519184736 15.643 100 82 164 0 1554019008 9183008 25651264
23:12:42 19665 8192000000 10568545280 10920198144 1613231920 16.452 100 80 160 0 1648708592 11350864 24125808
23:14:06 11733 8192000000 10627899984 10986573824 1682406288 17.038 100 88 176 0 1721116736 13518688 25191760
23:15:20 4908 8192000000 10669771568 11040772096 1734103392 17.470 100 82 164 1 1770592624 12704352 23784880
23:16:27 6039 8192000000 10714667008 11095932928 1783550704 17.879 100 79 158 0 1818669216 12548576 22569936
23:17:35 3600 8192000000 10773571856 11159089152 1827458768 18.239 100 86 172 1 1864876512 12947360 24470384
23:18:40 1437 8192000000 10803960240 11187052544 1858614784 18.492 100 95 191 0 1899539840 13963824 26961232
23:19:45 15276 8192000000 10806936912 11206316032 1871461056 18.596 100 66 129 2 1897208192 7320288 18426848
23:21:01 7583 8192000000 10802087504 11221491712 1880849696 18.672 100 33 66 2 1898105424 7744064 9511664
23:22:11 8320 8192000000 10805017360 11238412288 1899018144 18.818 100 19 38 1 1914018800 10325056 4675600
23:23:20 9569 8192000000 10824086336 11251474432 1905934880 18.874 100 24 48 1 1921736016 9847616 5953520
23:24:33 16155 8192000000 10823244480 11260485632 1911985760 18.923 100 20 40 0 1923315648 5696576 5633312
23:25:50 4708 8192000000 10838392080 11271446528 1921747312 19.001 100 27 54 1 1935443168 5944864 7750992
23:26:59 5493 8192000000 10823548368 11256569856 1909184368 18.900 100 15 30 1 1918825392 5603152 4037872
23:28:08 11498 8192000000 10784589360 11214540800 1822783632 18.200 97 17 34 1 1833392016 5556096 5052288
23:29:20 18762 8192000000 10778928432 11170910208 1820699968 18.183 98 73 144 1 1849294688 5627696 22967024
23:30:39 6441 8192000000 10733769936 11146096640 1798778256 18.004 99 26 51 0 1812180240 6198816 7203168
23:31:48 4466 8192000000 10730085904 11139997696 1794139072 17.966 99 35 70 3 1812046080 8136192 9770816
23:32:59 64511 8192000000 10754250128 11154128896 1802117152 18.031 99 27 54 1 1815562720 5390064 8055504
23:35:03 4913 8192000000 10772912496 11195719680 1848899936 18.413 100 38 76 0 1871747600 12848848 9998816
23:36:08 11415 8192000000 10781396208 11211788288 1862905312 18.527 100 12 24 0 1871421136 5178912 3336912
23:37:25 13241 8192000000 10805696048 11234074624 1891940352 18.761 100 14 28 2 1899957456 4059488 3957616
23:38:42 13710 8192000000 10845555376 11266519040 1920659152 18.992 100 45 90 0 1941852000 7093376 14099472
23:40:00 66058 8192000000 10857256512 11290390528 1934220768 19.101 100 45 89 2 1957372208 9623648 13527792
23:42:10 19531 8192000000 10871275200 11308470272 1927061488 19.043 99 33 66 2 1947642128 10720096 9860544
23:43:44 16027 8192000000 10892389600 11329773568 1964086432 19.339 100 32 64 0 1987505184 13818784 9599968
23:45:04 12377 8192000000 10928160336 11354685440 1982534416 19.485 100 58 115 3 2019456096 19080032 17841648
23:46:22 4975 8192000000 10960492832 11382829056 2000840080 19.629 100 68 133 1 2043120800 22972160 19308560
23:47:33 9492 8192000000 10964370976 11393060864 1989413104 19.539 99 48 95 3 2031862672 27086304 15363264
23:48:45 3855 8192000000 10996792864 11414700032 2025029680 19.820 100 74 145 2 2074566976 28512752 21024544
23:49:53 7052 8192000000 10983315184 11421454336 2018868000 19.771 100 61 121 1 2075596448 38837264 17891184
23:51:04 10000 8192000000 10968962288 11404984320 2009120864 19.695 100 60 119 1 2061807216 33784704 18901648
23:52:29 3283 8192000000 10952369360 11376615424 1970531216 19.390 99 71 140 1 2029031984 37555648 20945120
23:53:39 1918 8192000000 10952280288 11370737664 1973628304 19.414 100 80 160 1 2039130352 40348048 25154000
23:54:46 5988 8192000000 10936166992 11355561984 1949511248 19.223 99 73 146 0 2021518336 48433616 23573472
23:55:59 3264 8192000000 10985081776 11393605632 1980540272 19.469 100 86 172 1 2059347616 51460432 27346912
23:57:05 14570 8192000000 10919253888 11359109120 1907908832 18.890 97 31 62 1 1969101952 50297312 10895808
23:58:31 5000 8192000000 10919079408 11337719808 1921498208 18.999 98 66 133 0 1985837264 42157088 22181968
23:59:40 10102 8192000000 10906835072 11346886656 1924461792 19.023 99 35 70 0 1981370160 45861040 11047328
00:01:12 24024 8192000000 10894059696 11337039872 1915439872 18.950 99 29 56 2 1966723424 42600688 8682864
00:02:39 25224 8192000000 10917425376 11354374144 1932430464 19.086 100 37 74 3 1994736928 49614128 12692336
00:04:16 38134 8192000000 10924103136 11387314176 1938730592 19.137 100 20 40 0 2002538624 56490224 7317808
00:06:05 59307 8192000000 10878438752 11339165696 1939570320 19.143 100 7 14 0 1963570912 22210912 1789680
00:08:09 99300 8192000000 10873766240 11339583488 1929184416 19.060 98 28 55 0 1945696208 8601232 7910560
00:10:54 53111 8192000000 10903423328 11350736896 1958858224 19.297 100 20 40 1 1974378576 9627040 5893312
00:12:51 108151 8192000000 10898298944 11363885056 1937328944 19.125 98 15 31 0 1954343536 12769312 4245280
00:15:50 82904 8192000000 10878044800 11338641408 1949339168 19.221 100 16 32 2 1962760992 9008480 4413344
00:18:17 80261 8192000000 10886928304 11356962816 1945547072 19.191 99 13 24 0 1963688352 14479488 3661792
00:20:41 128663 8192000000 10898935104 11352207360 1958110928 19.291 100 19 38 1 1973821104 9672640 6037536
00:23:52 33883 8192000000 10915017008 11381862400 1987295648 19.522 100 25 50 1 2008479264 13947232 7236384
00:25:32 18928 8192000000 10920143056 11380019200 1990314656 19.546 100 43 87 3 2013370736 10571776 12484304
00:26:56 58859 8192000000 10909282912 11386396672 1993847184 19.574 100 11 22 1 2006137760 9051424 3239152
00:28:55 43454 8192000000 10911392352 11381706752 1993609760 19.572 100 29 58 0 2010244768 8230896 8404112
00:31:29 79089 8192000000 10899422816 11385208832 1973831360 19.416 99 16 32 0 1986471728 8298880 4341488
00:33:51 53354 8192000000 10915886880 11392778240 1997211760 19.601 100 27 54 1 2017353344 12998320 7143264
00:35:50 82198 8192000000 10909331200 11380903936 1967433488 19.365 99 19 37 2 1988947456 16915600 4598368
00:38:18 44993 8192000000 10903499232 11372916736 1930571296 19.071 97 21 42 0 1952071584 15453904 6046384
00:40:10 58002 8192000000 10908179008 11367727104 1914395936 18.942 96 23 46 0 1929154704 9085488 5673280
00:42:09 96791 8192000000 10912022096 11357896704 1856100544 18.472 94 18 36 0 1887574688 26953088 4521056
00:44:50 72837 8192000000 10891142000 11354415104 1803428352 18.042 91 13 26 0 1817418224 10456144 3533728
00:47:36 110835 8192000000 10874968128 11350753280 1855159152 18.464 95 7 14 1 1881844352 24448800 2236400
00:50:34 70120 8192000000 10858937232 11327365120 1847446672 18.401 94 28 57 0 1864171344 8865296 7859376
00:52:45 13052 8192000000 10856504752 11314020352 1925680704 19.032 99 36 74 0 1945656176 8894992 11080480
00:53:59 45349 8192000000 10852134304 11328851968 1927348416 19.046 99 14 28 0 1939412288 8535776 3528096
00:55:44 66196 8192000000 10848562272 11310952448 1838560752 18.329 95 16 32 3 1850656832 7496688 4599392
00:57:51 46736 8192000000 10839724736 11308281856 1903004240 18.850 99 15 30 0 1920883184 13620656 4258288
01:00:03 78776 8192000000 10853886688 11330142208 1922941216 19.010 99 12 25 0 1949833024 23983408 2908400
01:02:22 25156 8192000000 10876032000 11335827456 1944773168 19.185 100 35 69 1 1967743360 13923216 9046976
01:03:47 75337 8192000000 10869308224 11345821696 1917960096 18.970 98 17 34 2 1931890208 9403296 4526816
01:06:11 83172 8192000000 10872336224 11339259904 1875524768 18.629 96 13 26 1 1891981616 12911200 3545648
01:08:46 46170 8192000000 10877205824 11338096640 1914965360 18.946 98 22 44 0 1933856064 13250720 5639984
01:10:33 47810 8192000000 10896558656 11363864576 1965070336 19.346 100 31 62 4 1983968768 10249376 8649056
01:12:21 150518 8192000000 10889856096 11355041792 1858994480 18.495 94 21 42 1 1875109456 10964640 5150336
01:15:54 133130 8192000000 10913719120 11379671040 1979943328 19.464 100 11 22 0 2002193296 19198496 3051472
01:19:08 18702 8192000000 10917352592 11392270336 1986396992 19.515 100 17 34 1 2004330384 13130144 4803248
01:20:29 43308 8192000000 10918123424 11399737344 1983034528 19.489 99 17 31 2 2000146896 12887536 4224832
01:22:22 66659 8192000000 10901766160 11387273216 1938449632 19.134 97 10 20 0 1950815440 9504480 2861328
01:24:29 91291 8192000000 10885766448 11375079424 1940526656 19.151 98 12 24 0 1954159456 10159744 3473056
01:27:01 41469 8192000000 10875647456 11343896576 1901426976 18.838 97 30 60 1 1916372576 6753088 8192512
01:28:45 40522 8192000000 10895020464 11357581312 1908650336 18.896 97 22 44 0 1922704928 8308848 5745744
01:30:27 11307 8192000000 10890646192 11343179776 1945524496 19.191 99 41 82 0 1970098704 13037472 11536736
01:31:42 34831 8192000000 10899989152 11367047168 1965545488 19.350 100 32 64 1 1993987904 19745392 8697024
01:33:18 54781 8192000000 10884825024 11340922880 1847569264 18.402 94 28 56 0 1864385936 9089520 7727152
01:35:23 42306 8192000000 10872222384 11323830272 1856066112 18.471 95 30 60 2 1874904880 10766512 8072256
01:37:06 41454 8192000000 9210666928 9472966656 354178160 4.144 19 1 2 0 356684800 2444512 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 05-08-2021 01:38:32 to 05-08-2021 01:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-08-2021 01:38:48 to 05-08-2021 01:38:49.

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

05.08.21 01:38:51. 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-05 01:38:53 No severe PSQL-related problems occured
05.08.21 01:38:54. End of report.