Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.03.2021 10:28
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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 4794.26
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 16673816 kB
MemAvailable 31597760 kB
Buffers 0 kB
Cached 15013320 kB
SwapCached 0 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-03-15 10:26:53
DTS_END                         2021-03-15 10:26:53.9130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55704
TRN_ID                          232963
ATT_ID                          631
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: 9251.68 27523 1110202
customer order: creation 167.54 375 20105
customer order: refuse 14.23 334 1707
order to supplier: creation 44.74 1466 5369
order to supplier: removal 3.24 132108 389
invoice (draft): creation 44.68 3713 5361
invoice (draft): removal 53.17 934 6380
invoice accept: apply 40.64 19411 4877
invoice accept: cancel 48.03 913 5764
customer reserve: creation 3808.32 186 456998
customer reserve: removal 4736.00 71 568320
realization accept: apply 80.59 183 9671
realization accept: cancel 84.61 120 10153
payment from customer: creation 58.15 126 6978
payment from customer: removal 5.72 98 686
payment to supplier: creation 47.90 134 5748
payment to supplier: removal 3.27 72 392
service: total inventory turnovers 3.56 3924 427
service: total monetary turnovers 6.38 601 766
service: aggregate perf. data 0.89 16251 107
service: refresh index statistics 0.03 369432 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-03-2021 10:29:12 to 15-03-2021 10:29:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5019
TEST_TIME 2 5911
TEST_TIME 3 7131
TEST_TIME 4 7212
TEST_TIME 5 6785
TEST_TIME 6 6262
TEST_TIME 7 6204
TEST_TIME 8 6249
TEST_TIME 9 6428
TEST_TIME 10 6811
TEST_TIME 11 6642
TEST_TIME 12 6548
TEST_TIME 13 6469
TEST_TIME 14 6305
TEST_TIME 15 6467
TEST_TIME 16 6709
TEST_TIME 17 6871
TEST_TIME 18 7087
TEST_TIME 19 7031
TEST_TIME 20 7146
TEST_TIME 21 7403
TEST_TIME 22 7473
TEST_TIME 23 7911
TEST_TIME 24 8130
TEST_TIME 25 8498
TEST_TIME 26 8336
TEST_TIME 27 8625
TEST_TIME 28 9078
TEST_TIME 29 9145
TEST_TIME 30 9011
TEST_TIME 31 9558
TEST_TIME 32 11191
TEST_TIME 33 11010
TEST_TIME 34 11109
TEST_TIME 35 11125
TEST_TIME 36 11169
TEST_TIME 37 11022
TEST_TIME 38 10847
TEST_TIME 39 10658
TEST_TIME 40 10436
TEST_TIME 41 10342
TEST_TIME 42 10274
TEST_TIME 43 10217
TEST_TIME 44 10193
TEST_TIME 45 10254
TEST_TIME 46 10350
TEST_TIME 47 10198
TEST_TIME 48 10224
TEST_TIME 49 10065
TEST_TIME 50 10014
TEST_TIME 51 10087
TEST_TIME 52 10170
TEST_TIME 53 10111
TEST_TIME 54 10056
TEST_TIME 55 10073
TEST_TIME 56 10050
TEST_TIME 57 9963
TEST_TIME 58 10111
TEST_TIME 59 10051
TEST_TIME 60 10040
TEST_TIME 61 10214
TEST_TIME 62 10230
TEST_TIME 63 10078
TEST_TIME 64 9942
TEST_TIME 65 9907
TEST_TIME 66 9803
TEST_TIME 67 9719
TEST_TIME 68 9686
TEST_TIME 69 9630
TEST_TIME 70 9567
TEST_TIME 71 9606
TEST_TIME 72 9548
TEST_TIME 73 9538
TEST_TIME 74 9680
TEST_TIME 75 9647
TEST_TIME 76 9630
TEST_TIME 77 9649
TEST_TIME 78 9621
TEST_TIME 79 9674
TEST_TIME 80 9675
TEST_TIME 81 9676
TEST_TIME 82 9722
TEST_TIME 83 9809
TEST_TIME 84 9745
TEST_TIME 85 9644
TEST_TIME 86 9591
TEST_TIME 87 9547
TEST_TIME 88 9478
TEST_TIME 89 9454
TEST_TIME 90 9442
TEST_TIME 91 9462
TEST_TIME 92 9436
TEST_TIME 93 9423
TEST_TIME 94 9440
TEST_TIME 95 9415
TEST_TIME 96 9490
TEST_TIME 97 9448
TEST_TIME 98 9417
TEST_TIME 99 9410
TEST_TIME 100 9580
TEST_TIME 101 9563
TEST_TIME 102 9476
TEST_TIME 103 9414
TEST_TIME 104 9394
TEST_TIME 105 9392
TEST_TIME 106 9378
TEST_TIME 107 9357
TEST_TIME 108 9305
TEST_TIME 109 9306
TEST_TIME 110 9266
TEST_TIME 111 9377
TEST_TIME 112 9352
TEST_TIME 113 9314
TEST_TIME 114 9336
TEST_TIME 115 9421
TEST_TIME 116 9407
TEST_TIME 117 9364
TEST_TIME 118 9304
TEST_TIME 119 9269
TEST_TIME 120 9263

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-03-2021 10:29:16 to 15-03-2021 10:29:19.

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 1533677 1533677 0 0.00 0 15949 7 0 0
doc_list_biud 1533675 1533675 0 0.00 0 25143 33 0 0
sp_add_invoice_to_stock 4964 4877 87 1.75 36 176027 19411 0 87
sp_cancel_adding_invoice 5764 5764 0 0.00 5 76779 913 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 11224 335 0 0
sp_cancel_customer_reserve 568320 568320 0 0.00 1 18261 71 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 6106 99 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 3611 73 0 0
sp_cancel_supplier_invoice 6391 6380 11 0.17 2 22003 935 0 11
sp_cancel_supplier_order 389 389 0 0.00 9 1278038 132108 0 0
sp_cancel_write_off 10153 10153 0 0.00 1 12634 120 0 0
sp_client_order 20105 20105 0 0.00 4 23162 376 0 0
sp_customer_reserve 456998 456998 0 0.00 0 44616 187 0 0
sp_fill_shopping_cart 30833 30833 0 0.00 1 29103 42 0 0
sp_fill_shopping_cart_clo_res 456982 456982 0 0.00 0 34381 17 0 0
sp_get_clo_for_invoice 4857 4857 0 0.00 0 34639 362 0 0
sp_kill_cost_storno 16995 16995 0 0.00 0 5742 58 0 0
sp_kill_qstorno_ret_qs2qd 1153562 1153562 0 0.00 0 21991 38 0 0
sp_kill_qty_storno 607241 607241 0 0.00 0 25143 82 0 0
sp_lock_selected_doc 20050 20050 0 0.00 0 7767 6 0 0
sp_make_cost_storno 27254 27254 0 0.00 0 15163 62 0 0
sp_multiply_rows_for_pdistr 27254 27254 0 0.00 0 6889 33 0 0
sp_multiply_rows_for_qdistr 20105 20105 0 0.00 0 23154 196 0 0
sp_pay_from_customer 6978 6978 0 0.00 4 13855 127 0 0
sp_pay_to_supplier 5748 5748 0 0.00 5 15953 135 0 0
sp_payment_common 12726 12726 0 0.00 1 9103 18 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 11205 302 0 0
sp_qd_handle_on_invoice_upd_sts 10621 10621 0 0.00 0 25143 397 0 0
sp_qd_handle_on_reserve_upd_sts 9671 9671 0 0.00 0 10575 44 0 0
sp_reserve_write_off 9671 9671 0 0.00 7 14226 183 0 0
sp_supplier_invoice 5361 5361 0 0.00 7 126172 3713 0 0
sp_supplier_order 5369 5369 0 0.00 6 46921 1467 0 0
srv_aggregate_perf_data 107 107 0 0.00 2842 207332 16252 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 933 131161 41466 0 0
srv_find_qd_qs_mism 1063563 1063563 0 0.00 0 15335 6 0 0
srv_make_invnt_saldo 427 427 0 0.00 65 113647 3924 0 0
srv_make_money_saldo 766 766 0 0.00 1 13032 602 0 0
srv_recalc_idx_stat 4 4 0 0.00 219387 453327 369432 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 11468 186 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 1905 78 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 4865 15 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 4399 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 757 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 3615 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 983 18 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 22066 1718 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 1605 8 0 0
t$perf-norm:sp_client_order 20105 20105 0 0.00 0 6336 18 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 2642 7 0 0
t$perf-norm:sp_pay_from_customer 6978 6978 0 0.00 0 3344 8 0 0
t$perf-norm:sp_pay_to_supplier 5748 5748 0 0.00 0 4708 8 0 0
t$perf-norm:sp_reserve_write_off 9671 9671 0 0.00 0 9329 11 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 4693 22 0 0
t$perf-norm:sp_supplier_order 5369 5369 0 0.00 0 3731 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 512 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 286 9 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 4134 23 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 370 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 50 14 0 0
v_add_invoice_to_stock 9886 9712 174 1.76 0 9893 21 0 174
v_all_customers 37064 37064 0 0.00 0 15 1 0 0
v_all_suppliers 29841 29841 0 0.00 0 15 1 0 0
v_all_wares 40210 40210 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 5288 20 0 0
v_cancel_client_order 3414 3414 0 0.00 0 5456 10 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2424 9 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 2678 10 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 3592 12 0 0
v_cancel_supplier_invoice 812 790 22 2.70 0 1090 15 0 22
v_cancel_supplier_order 778 778 0 0.00 0 5208 16 0 0
v_cancel_write_off 1872 1872 0 0.00 0 2314 17 0 0
v_max_id_clo_ord 5369 5369 0 0.00 0 317 1 0 0
v_max_id_clo_res 970 970 0 0.00 0 2291 23 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 4792 5 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 217 2 0 0
v_max_non_paid_realizn 3505 3505 0 0.00 0 2989 5 0 0
v_min_id_clo_ord 5369 5369 0 0.00 0 4320 6 0 0
v_min_id_clo_res 970 970 0 0.00 0 6127 21 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 9111 6 0 0
v_min_non_paid_invoice 5747 5747 0 0.00 0 5472 11 0 0
v_min_non_paid_realizn 6978 6978 0 0.00 0 4984 13 0 0
v_our_firm 10092 10092 0 0.00 0 24 1 0 0
v_reserve_write_off 19340 19340 0 0.00 0 7144 9 0 0
x_lock_depdocs_on_canc_invoice 11749 11749 0 0.00 0 15995 119 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 23396 424 0 0
x_make_qstorno_1000_1200 5369 5369 0 0.00 2 46910 1384 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 4 109695 3557 0 0
x_make_qstorno_2100_3300 456998 456998 0 0.00 0 36318 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-03-2021 10:29:20 to 15-03-2021 10:29:24.

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
07:56:57 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:57:58 34748 8192000000 9725026672 10022830080 802150928 8.918 100 85 170 2 830455216 7986480 20317808
07:59:38 27920 8192000000 9762787280 10070282240 836665456 9.266 100 92 184 0 872218400 13741248 21811696
08:01:17 16455 8192000000 9835991360 10155732992 882411472 9.724 100 86 169 2 940218848 38820976 18986400
08:02:35 9939 8192000000 9898384784 10236645376 930895440 10.203 100 76 151 0 1006639264 59428464 16315360
08:03:47 8880 8192000000 9939140304 10285969408 974323440 10.629 100 83 166 0 1046250336 54686544 17240352
08:04:58 4007 8192000000 9989338336 10345590784 1005922608 10.936 100 89 175 3 1102596640 79138000 17536032
08:06:04 5612 8192000000 9998807200 10365415424 1028599040 11.155 100 84 166 0 1115767056 69899232 17268784
08:07:13 1632 8192000000 10030668960 10399719424 1063965504 11.494 100 92 184 0 1141487776 58834144 18688128
08:08:15 7705 8192000000 10001423136 10385977344 1068452672 11.537 100 51 102 2 1116421264 37675728 10292864
08:09:24 5461 8192000000 9997770032 10406580224 1083572096 11.681 100 32 64 1 1123024256 32760080 6692080
08:10:33 9613 8192000000 10004609696 10417704960 1088887776 11.732 100 22 43 4 1117821136 24282416 4650944
08:11:44 61417 8192000000 10016368800 10430492672 1096820112 11.807 100 29 57 1 1132867824 30159664 5888048
08:13:50 8845 8192000000 10030187360 10436829184 1101660208 11.853 100 41 82 1 1145082448 33921792 9500448
08:15:04 5532 8192000000 10021334096 10425348096 1103965776 11.875 100 26 49 0 1135090896 25529536 5595584
08:16:11 4409 8192000000 10017233696 10424295424 1101832944 11.855 100 41 82 2 1137850112 27489072 8528096
08:17:22 22970 8192000000 9994531792 10403430400 1065382848 11.508 97 19 37 2 1101851504 32206256 4262400
08:18:46 6721 8192000000 9954962960 10343931904 988808112 10.770 94 38 74 1 1018624944 21856608 7960224
08:19:56 7168 8192000000 9963629152 10355302400 992786160 10.809 95 44 88 2 1036329136 34122976 9420000
08:21:05 64836 8192000000 9937982560 10338656256 985199664 10.735 96 41 82 0 1014965536 20951248 8814624
08:23:10 6087 8192000000 9989725408 10375139328 1044652528 11.309 100 66 132 0 1088361968 28940064 14769376
08:24:20 7467 8192000000 9971451392 10364039168 1056126400 11.419 100 42 86 1 1086190864 20100896 9963568
08:25:28 5808 8192000000 9993078560 10388357120 1071779056 11.569 100 48 96 2 1101442304 18290928 11372320
08:26:37 49789 8192000000 9989071472 10385649664 1076206176 11.611 100 44 88 1 1101066368 15196336 9663856
08:28:31 981 8192000000 10042415632 10432331776 1092569008 11.767 100 84 166 4 1152492448 42479872 17443568
08:29:35 4965 8192000000 10058135104 10459668480 1100531440 11.843 100 63 125 1 1172135264 58686448 12917376
08:30:47 13559 8192000000 10064271184 10455199744 1112888160 11.960 100 64 123 0 1153761984 26855072 14018752
08:32:09 12837 8192000000 10088168928 10493550592 1131219216 12.133 100 57 108 2 1193771232 50944704 11607312
08:33:32 2319 8192000000 10132787120 10534141952 1144000096 12.253 100 64 127 1 1242410528 85064464 13345968
08:34:37 6111 8192000000 10168253488 10574000128 1145407008 12.266 100 56 109 0 1274471712 117070080 11994624
08:35:49 6075 8192000000 10161720880 10582384640 1145926176 12.271 100 39 78 1 1273609440 119080544 8602720
08:37:01 6603 8192000000 10174985120 10583552000 1167172288 12.470 100 64 125 0 1281816592 102033808 12610496
08:38:16 1785 8192000000 10207980352 10613698560 1171010400 12.506 100 62 121 0 1311602384 127483056 13108928
08:39:22 6812 8192000000 10214583904 10630955008 1139338928 12.209 98 49 98 0 1309245248 159362432 10543888
08:40:40 3186 8192000000 10273573712 10678685696 1155279760 12.359 99 71 140 0 1370062592 199695504 15087328
08:41:48 4694 8192000000 10305735168 10717167616 1159575472 12.399 99 70 135 1 1401700368 226598256 15526640
08:42:59 4839 8192000000 10296602640 10696073216 1145702368 12.269 99 77 152 0 1389995504 227595920 16697216
08:44:08 1895 8192000000 10336151744 10736513024 1156960464 12.375 100 84 165 0 1436604992 260561216 19083312
08:45:14 2990 8192000000 10374677680 10774011904 1152833392 12.336 100 84 167 0 1473975840 302015936 19126512
08:46:21 2839 8192000000 10436157488 10835914752 1159576080 12.399 100 90 179 1 1535188304 354425968 21186256
08:47:26 932 8192000000 10455967840 10847301632 1145984176 12.272 100 94 188 0 1552408192 384574016 21850000
08:48:33 2870 8192000000 10484147440 10882093056 1152379328 12.332 99 86 170 1 1579487968 406704176 20404464
08:49:44 3229 8192000000 10495355888 10905104384 1165693312 12.457 100 60 120 0 1591005760 410321392 14991056
08:50:50 17068 8192000000 10516273280 10943696896 1173156304 12.526 100 42 84 0 1614519680 430188736 11174640
08:52:10 8275 8192000000 10513097104 10936348672 1163597024 12.437 99 49 97 0 1605032864 428463808 12972032
08:53:22 10109 8192000000 10551988176 11004264448 1154711680 12.354 99 46 92 0 1649095728 481944656 12439392
08:54:37 18735 8192000000 10505319808 10972647424 1171256240 12.509 100 35 70 1 1606762304 426058496 9447568
08:55:57 9661 8192000000 10503997456 10986655744 1158271872 12.387 100 22 44 1 1600659440 436016656 6370912
08:57:46 15988 8192000000 10413670352 10896666624 1134397984 12.163 100 22 44 0 1534855840 394343264 6114592
08:59:15 37682 8192000000 10091382816 10521804800 1131887600 12.139 100 37 75 0 1196184448 55462976 8833872
09:00:59 96779 8192000000 10074716176 10568699904 1107411232 11.908 98 6 12 0 1159272608 50686656 1174720
09:03:37 84601 8192000000 10095709296 10537398272 1135154128 12.170 100 22 44 0 1174683424 35303328 4225968
09:06:31 33631 8192000000 10099520832 10538041344 1133948048 12.159 99 35 70 0 1195111824 53838752 7325024
09:08:07 46758 8192000000 10116497776 10556760064 1136914352 12.186 99 20 40 1 1200930688 60216288 3800048
09:09:54 75544 8192000000 10178899376 10690854912 1142905776 12.243 99 28 56 3 1309803680 160847600 6050304
09:12:19 67466 8192000000 10114424448 10601267200 1157202592 12.377 100 12 24 0 1202103040 42312880 2587568
09:14:29 45273 8192000000 10113652064 10606510080 1160547184 12.408 100 13 26 0 1255989168 92293344 3148640
09:16:38 71494 8192000000 10119734384 10610937856 1151448752 12.323 99 9 19 0 1215654672 62379008 1826912
09:18:50 69224 8192000000 10061685520 10516783104 1107366352 11.907 96 29 57 0 1132554864 19031296 6157216
09:21:00 60452 8192000000 10093181200 10564415488 1146007984 12.272 100 17 34 2 1188355424 39167152 3180288
09:23:02 124822 8192000000 10083615200 10551554048 1097061152 11.810 95 14 28 0 1129090816 28746400 3283264
09:26:07 55835 8192000000 10151443840 10614784000 1142964800 12.243 99 24 48 0 1228093424 80206240 4922384
09:28:03 131161 8192000000 10085399216 10571927552 1129943024 12.121 98 16 32 0 1167310384 34167472 3199888
09:31:35 72589 8192000000 10063453984 10528292864 1096620608 11.806 97 9 18 0 1130090528 31292064 2177856
09:33:48 69059 8192000000 10086955504 10549964800 1128102896 12.103 100 17 34 0 1204554656 72565344 3886416
09:35:58 56350 8192000000 10109272400 10575507456 1115234592 11.982 98 10 20 0 1185187376 68037936 1914848
09:37:54 57875 8192000000 10099304816 10548314112 1091262416 11.755 95 19 38 0 1154101712 58892464 3946832
09:39:53 57082 8192000000 10060252080 10543853568 1088495344 11.728 97 13 26 4 1181586144 90235632 2855168
09:42:06 36252 8192000000 10066919648 10495418368 1099110944 11.829 98 27 54 1 1148395024 43743360 5540720
09:43:43 50732 8192000000 10084701984 10525048832 1101012128 11.847 99 36 72 0 1187468960 79497280 6959552
09:46:55 19447 8192000000 10106472032 10540314624 1122443664 12.050 100 26 52 3 1193446208 65370512 5632032
09:48:26 45603 8192000000 10133127200 10606637056 1114358752 11.974 98 14 28 2 1238222864 120380896 3483216
09:50:14 26088 8192000000 10059032480 10486411264 1100960096 11.847 98 50 99 0 1133730864 22274736 10496032
09:51:46 95699 8192000000 10096376144 10546749440 1127155776 12.095 100 27 54 2 1197877440 64847008 5874656
09:54:24 50799 8192000000 10113327216 10592571392 1124120208 12.066 99 10 20 0 1216056288 89829504 2106576
09:56:15 50435 8192000000 10074543264 10529697792 1099571584 11.834 97 10 21 1 1156987152 54330560 3085008
09:58:19 45683 8192000000 10086069856 10537078784 1123407744 12.059 99 15 30 0 1179339024 52963408 2967872
10:00:08 70361 8192000000 10116034432 10556674048 1137521152 12.192 100 26 52 2 1206371232 63250576 5599504
10:02:26 55061 8192000000 10070378896 10497163264 1114323264 11.973 98 16 32 0 1145197344 27599392 3274688
10:04:25 33992 8192000000 10156792736 10593767424 1148205888 12.293 100 27 52 3 1245785936 91857072 5722976
10:06:00 48443 8192000000 10149566304 10658344960 1146878208 12.280 100 29 58 2 1234993616 82314640 5800768
10:07:50 96618 8192000000 10070637456 10521853952 1147364384 12.285 99 24 48 1 1187766832 35785664 4616784
10:10:28 68437 8192000000 10094431872 10588688384 1135679088 12.175 98 6 12 0 1168229216 31529888 1020240
10:12:36 66179 8192000000 10101915232 10574729216 1164486096 12.445 100 12 24 0 1207996672 41314528 2196048
10:14:46 53674 8192000000 10104619328 10563006464 1158877312 12.393 100 22 44 0 1207299456 43555472 4866672
10:16:43 97474 8192000000 10074034144 10547310592 1113941504 11.970 96 19 38 0 1197599216 79389568 4268144
10:19:38 67238 8192000000 10126960592 10600349696 1131488656 12.135 98 24 47 2 1186783632 50389824 4905152
10:21:56 32621 8192000000 10091782880 10534273024 1142451968 12.239 99 13 26 0 1171962128 27007104 2503056
10:23:42 114042 8192000000 10142381216 10593624064 1113141936 11.962 97 34 66 0 1208725792 89177808 6406048
10:26:46 20482 8192000000 9413771888 9761169408 538510672 6.168 48 3 6 0 551520640 12675584 334384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-03-2021 10:29:26 to 15-03-2021 10:29:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-03-2021 10:29:42 to 15-03-2021 10:29:44.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233175
MON$OLDEST_ACTIVE               233176
MON$OLDEST_SNAPSHOT             233176
MON$NEXT_TRANSACTION            233176
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-30 07:30:08.6030 GMT
MON$PAGES                       2127104
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:115: 
:116: mscs-test-161.budzdorov.ru    Mon Mar 15 10:27:13 2021
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Mon Mar 15 10:27:14 2021
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Mon Mar 15 10:27:15 2021
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:126: 
--- end of diff output ---

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

15.03.21 10:29:45. There are 3 logs in /var/tmp/logs-oltp40 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-03-15 10:29:48 No severe PSQL-related problems occured
15.03.21 10:29:49. End of report.