Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2021 13:39
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 15785920 kB
MemAvailable 30925212 kB
Buffers 0 kB
Cached 15946648 kB
SwapCached 4760 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.1
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-10-25 13:37:10
DTS_END                         2021-10-25 13:37:10.0930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46434
TRN_ID                          230681
ATT_ID                          656
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: 9236.74 25226 1108409
customer order: creation 168.62 396 20234
customer order: refuse 14.79 377 1775
order to supplier: creation 43.85 1491 5262
order to supplier: removal 3.58 107233 430
invoice (draft): creation 43.85 3708 5262
invoice (draft): removal 52.76 933 6331
invoice accept: apply 40.58 20149 4870
invoice accept: cancel 47.83 868 5739
customer reserve: creation 3822.85 195 458742
customer reserve: removal 4705.97 63 564716
realization accept: apply 81.38 229 9765
realization accept: cancel 85.88 127 10305
payment from customer: creation 57.18 138 6862
payment from customer: removal 5.64 92 677
payment to supplier: creation 47.31 156 5677
payment to supplier: removal 3.68 128 441
service: total inventory turnovers 3.57 3866 428
service: total monetary turnovers 6.52 464 782
service: aggregate perf. data 0.89 15552 107
service: refresh index statistics 0.03 348352 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2021 13:39:31 to 25-10-2021 13:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3072
TEST_TIME 2 3782
TEST_TIME 3 2770
TEST_TIME 4 2673
TEST_TIME 5 3482
TEST_TIME 6 3695
TEST_TIME 7 3648
TEST_TIME 8 4142
TEST_TIME 9 5335
TEST_TIME 10 5508
TEST_TIME 11 6128
TEST_TIME 12 6045
TEST_TIME 13 6122
TEST_TIME 14 6410
TEST_TIME 15 6430
TEST_TIME 16 7162
TEST_TIME 17 7447
TEST_TIME 18 7584
TEST_TIME 19 8010
TEST_TIME 20 7874
TEST_TIME 21 8349
TEST_TIME 22 8340
TEST_TIME 23 8425
TEST_TIME 24 8331
TEST_TIME 25 8806
TEST_TIME 26 9153
TEST_TIME 27 9680
TEST_TIME 28 9455
TEST_TIME 29 9263
TEST_TIME 30 9816
TEST_TIME 31 9940
TEST_TIME 32 10375
TEST_TIME 33 10228
TEST_TIME 34 10190
TEST_TIME 35 9969
TEST_TIME 36 10050
TEST_TIME 37 10087
TEST_TIME 38 10040
TEST_TIME 39 10109
TEST_TIME 40 10126
TEST_TIME 41 10126
TEST_TIME 42 10038
TEST_TIME 43 10185
TEST_TIME 44 10084
TEST_TIME 45 9882
TEST_TIME 46 9827
TEST_TIME 47 9780
TEST_TIME 48 9765
TEST_TIME 49 9674
TEST_TIME 50 9646
TEST_TIME 51 9665
TEST_TIME 52 9653
TEST_TIME 53 9735
TEST_TIME 54 9650
TEST_TIME 55 9610
TEST_TIME 56 9538
TEST_TIME 57 9501
TEST_TIME 58 9454
TEST_TIME 59 9450
TEST_TIME 60 9490
TEST_TIME 61 9389
TEST_TIME 62 9367
TEST_TIME 63 9446
TEST_TIME 64 9424
TEST_TIME 65 9566
TEST_TIME 66 9583
TEST_TIME 67 9575
TEST_TIME 68 9633
TEST_TIME 69 9708
TEST_TIME 70 9709
TEST_TIME 71 9699
TEST_TIME 72 9735
TEST_TIME 73 9779
TEST_TIME 74 9793
TEST_TIME 75 9711
TEST_TIME 76 9602
TEST_TIME 77 9605
TEST_TIME 78 9568
TEST_TIME 79 9561
TEST_TIME 80 9515
TEST_TIME 81 9441
TEST_TIME 82 9367
TEST_TIME 83 9299
TEST_TIME 84 9297
TEST_TIME 85 9369
TEST_TIME 86 9323
TEST_TIME 87 9304
TEST_TIME 88 9343
TEST_TIME 89 9358
TEST_TIME 90 9345
TEST_TIME 91 9413
TEST_TIME 92 9424
TEST_TIME 93 9468
TEST_TIME 94 9451
TEST_TIME 95 9477
TEST_TIME 96 9477
TEST_TIME 97 9553
TEST_TIME 98 9622
TEST_TIME 99 9531
TEST_TIME 100 9453
TEST_TIME 101 9461
TEST_TIME 102 9408
TEST_TIME 103 9372
TEST_TIME 104 9337
TEST_TIME 105 9290
TEST_TIME 106 9246
TEST_TIME 107 9204
TEST_TIME 108 9242
TEST_TIME 109 9262
TEST_TIME 110 9232
TEST_TIME 111 9214
TEST_TIME 112 9299
TEST_TIME 113 9268
TEST_TIME 114 9237
TEST_TIME 115 9326
TEST_TIME 116 9307
TEST_TIME 117 9330
TEST_TIME 118 9324
TEST_TIME 119 9315
TEST_TIME 120 9237

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-10-2021 13:39:34 to 25-10-2021 13:39:38.

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 1532626 1532626 0 0.00 0 32903 8 0 0
doc_list_biud 1532624 1532624 0 0.00 0 82021 30 0 0
sp_add_invoice_to_stock 5002 4870 132 2.63 36 190632 20150 0 132
sp_cancel_adding_invoice 5739 5739 0 0.00 3 65817 868 0 0
sp_cancel_client_order 1775 1775 0 0.00 5 14327 378 0 0
sp_cancel_customer_reserve 564716 564716 0 0.00 1 24851 63 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 5062 92 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 7051 129 0 0
sp_cancel_supplier_invoice 6343 6331 12 0.18 2 82041 934 0 12
sp_cancel_supplier_order 430 430 0 0.00 7 925515 107234 0 0
sp_cancel_write_off 10305 10305 0 0.00 1 21528 127 0 0
sp_client_order 20234 20234 0 0.00 4 19317 397 0 0
sp_customer_reserve 458742 458742 0 0.00 0 76976 195 0 0
sp_fill_shopping_cart 30752 30752 0 0.00 1 19611 45 0 0
sp_fill_shopping_cart_clo_res 458740 458740 0 0.00 0 15923 15 0 0
sp_get_clo_for_invoice 4868 4868 0 0.00 0 18642 342 0 0
sp_kill_cost_storno 17162 17162 0 0.00 0 21508 68 0 0
sp_kill_qstorno_ret_qs2qd 1146498 1146498 0 0.00 0 82017 35 0 0
sp_kill_qty_storno 603929 603929 0 0.00 0 82020 75 0 0
sp_lock_selected_doc 20399 20399 0 0.00 0 6321 4 0 0
sp_make_cost_storno 27172 27172 0 0.00 0 32892 77 0 0
sp_multiply_rows_for_pdistr 27171 27171 0 0.00 0 21503 43 0 0
sp_multiply_rows_for_qdistr 20234 20234 0 0.00 0 19072 201 0 0
sp_pay_from_customer 6862 6862 0 0.00 4 19958 139 0 0
sp_pay_to_supplier 5677 5677 0 0.00 5 19060 157 0 0
sp_payment_common 12538 12538 0 0.00 1 19946 29 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 14301 353 0 0
sp_qd_handle_on_invoice_upd_sts 10607 10607 0 0.00 0 36187 382 0 0
sp_qd_handle_on_reserve_upd_sts 9765 9765 0 0.00 0 19016 62 0 0
sp_reserve_write_off 9765 9765 0 0.00 7 21704 230 0 0
sp_supplier_invoice 5262 5262 0 0.00 8 87512 3709 0 0
sp_supplier_order 5262 5262 0 0.00 6 42153 1492 0 0
srv_aggregate_perf_data 107 107 0 0.00 2649 131359 15553 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1754 163712 44649 0 0
srv_find_qd_qs_mism 1061451 1061451 0 0.00 0 16166 6 0 0
srv_make_invnt_saldo 428 428 0 0.00 61 79044 3867 0 0
srv_make_money_saldo 782 782 0 0.00 1 14440 464 0 0
srv_recalc_idx_stat 4 4 0 0.00 271062 446610 348353 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 0 10199 183 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 2274 93 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 4999 20 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 3060 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 523 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 4180 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 2812 25 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 20866 1526 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 596 4 0 0
t$perf-norm:sp_client_order 20234 20234 0 0.00 0 12904 22 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 624 5 0 0
t$perf-norm:sp_pay_from_customer 6862 6862 0 0.00 0 5530 11 0 0
t$perf-norm:sp_pay_to_supplier 5677 5677 0 0.00 0 6753 11 0 0
t$perf-norm:sp_reserve_write_off 9765 9765 0 0.00 0 12163 14 0 0
t$perf-norm:sp_supplier_invoice 5262 5262 0 0.00 0 7288 21 0 0
t$perf-norm:sp_supplier_order 5262 5262 0 0.00 0 5787 22 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 635 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1033 19 0 0
t$perf-norm:srv_make_invnt_saldo 428 428 0 0.00 0 3948 24 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 2810 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10000 9736 264 2.64 0 4717 19 0 264
v_all_customers 36794 36794 0 0.00 0 55 1 0 0
v_all_suppliers 29100 29100 0 0.00 0 22 1 0 0
v_all_wares 40468 40468 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 2193 11 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2185 5 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 2648 7 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 736 4 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 3219 14 0 0
v_cancel_supplier_invoice 812 788 24 2.95 0 1459 15 0 24
v_cancel_supplier_order 860 860 0 0.00 0 1945 9 0 0
v_cancel_write_off 1992 1992 0 0.00 0 1955 16 0 0
v_max_id_clo_ord 5261 5261 0 0.00 0 2575 2 0 0
v_max_id_clo_res 951 951 0 0.00 0 6609 26 0 0
v_max_id_ord_sup 5256 5256 0 0.00 0 3123 3 0 0
v_max_non_paid_invoice 1644 1644 0 0.00 0 191 2 0 0
v_max_non_paid_realizn 3560 3560 0 0.00 0 8301 7 0 0
v_min_id_clo_ord 5261 5261 0 0.00 0 12138 17 0 0
v_min_id_clo_res 951 951 0 0.00 0 4887 12 0 0
v_min_id_ord_sup 5256 5256 0 0.00 0 12169 15 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 14557 20 0 0
v_min_non_paid_realizn 6861 6861 0 0.00 0 19937 22 0 0
v_our_firm 10276 10276 0 0.00 0 141 1 0 0
v_reserve_write_off 19530 19530 0 0.00 0 7066 7 0 0
x_lock_depdocs_on_canc_invoice 11676 11676 0 0.00 0 15326 116 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 12690 371 0 0
x_make_qstorno_1000_1200 5262 5262 0 0.00 2 42143 1393 0 0
x_make_qstorno_1200_2000 5262 5262 0 0.00 3 84248 3546 0 0
x_make_qstorno_2100_3300 458742 458742 0 0.00 0 68158 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2021 13:39:39 to 25-10-2021 13:39:43.

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
11:07:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 18933 8192000000 9715811888 10008088576 789613104 8.791 100 87 172 1 818225856 7693184 20919568
11:09:46 23977 8192000000 9766174448 10070122496 836005536 9.260 100 89 178 0 868627536 11808816 20813184
11:11:24 12388 8192000000 9797083968 10116227072 873755984 9.637 100 78 156 0 910603248 19225232 17622032
11:12:49 16959 8192000000 9884411840 10215243776 915395872 10.051 100 81 162 1 986163568 53178752 17588944
11:14:09 11327 8192000000 9937558464 10280108032 960079824 10.490 100 83 166 0 1046800672 69586656 17134192
11:15:24 3651 8192000000 9972410480 10326327296 996355120 10.843 100 84 168 0 1082715072 69505552 16854400
11:16:30 2945 8192000000 9987597280 10349645824 1023178224 11.103 100 86 172 2 1101775744 61689360 16908160
11:17:34 1666 8192000000 10020587152 10387587072 1049463280 11.356 100 92 184 0 1136093168 67558976 19070912
11:18:37 2096 8192000000 10052155824 10423181312 1064165632 11.496 100 92 186 1 1161217312 77775584 19276096
11:19:41 1604 8192000000 10026324592 10403840000 1069196320 11.544 100 89 179 2 1137321280 49205808 18919152
11:20:45 7559 8192000000 9986761504 10378194944 1078277456 11.631 100 37 73 0 1105047344 18486448 8283440
11:21:53 6458 8192000000 10000281680 10404982784 1087460512 11.719 100 41 82 2 1117801440 21843008 8497920
11:23:02 9790 8192000000 10000088048 10403028992 1093564800 11.777 100 27 54 1 1120975008 21766832 5643376
11:24:12 34469 8192000000 10032929584 10422071296 1098987072 11.828 100 48 97 3 1135214880 25220384 11007424
11:25:47 8130 8192000000 10026140976 10420297728 1092631808 11.768 99 48 96 2 1119146720 15139376 11375536
11:26:58 4511 8192000000 10025989056 10427400192 1103192448 11.868 100 42 85 0 1146229328 33802976 9233904
11:28:03 7586 8192000000 10013645008 10415181824 1062951504 11.485 97 32 65 0 1098701088 29596032 6153552
11:29:11 23772 8192000000 9964838720 10369155072 1027132512 11.141 95 22 44 0 1062655600 31306576 4216512
11:30:37 6719 8192000000 9944037680 10323042304 1015688256 11.030 99 30 61 0 1051213168 28847664 6677248
11:31:48 6219 8192000000 9930159872 10299043840 972838544 10.614 94 45 88 3 994746288 12181248 9726496
11:32:55 63383 8192000000 9928274880 10329993216 999773664 10.876 96 31 61 2 1033620720 27142048 6705008
11:34:59 7189 8192000000 9989679872 10389082112 1054319952 11.402 100 43 86 1 1089074800 25419120 9335728
11:36:07 6672 8192000000 9979546960 10375323648 1065085296 11.505 100 34 69 0 1095479120 22890384 7503440
11:37:20 30410 8192000000 10013836928 10408112128 1094288752 11.783 100 51 102 2 1122585504 17533664 10763088
11:38:56 163711 8192000000 10089870592 10496548864 1112966928 11.960 100 41 79 3 1176393408 53764064 9662416
11:42:44 32168 8192000000 10116514416 10529882112 1133169424 12.151 100 39 78 1 1247448272 105719328 8559520
11:44:20 21143 8192000000 10171254928 10576805888 1147638704 12.287 100 55 110 2 1268655856 109008560 12008592
11:45:45 4592 8192000000 10209235712 10605162496 1149587504 12.306 100 71 142 0 1308434800 142757248 16090048
11:46:55 6322 8192000000 10196758960 10603118592 1157574928 12.381 100 72 142 0 1300426448 127207280 15644240
11:48:11 2042 8192000000 10217962176 10635407360 1173004624 12.525 100 50 98 1 1330907696 146308496 11594576
11:49:17 11690 8192000000 10215215376 10632704000 1168390064 12.482 99 46 92 0 1306577248 127880112 10307072
11:50:33 2676 8192000000 10237128336 10651115520 1177252560 12.565 100 63 126 0 1341647680 150121536 14273584
11:51:43 2855 8192000000 10293326512 10703970304 1189160176 12.676 100 77 154 1 1392455552 186520096 16775280
11:52:51 1752 8192000000 10328311952 10732163072 1178782448 12.579 100 96 192 0 1425085360 224529232 21773680
11:53:58 2224 8192000000 10297207904 10697678848 1172255616 12.518 99 87 172 0 1387459856 195110048 20094192
11:55:04 3361 8192000000 10303028080 10705797120 1160551856 12.408 99 74 147 1 1399072512 222679664 15840992
11:56:13 3111 8192000000 10296057200 10698043392 1154850704 12.355 100 76 150 1 1394193728 222727008 16616016
11:57:22 1852 8192000000 10298828032 10702389248 1153462128 12.342 100 79 159 1 1395819744 223226448 19131168
11:58:30 13929 8192000000 10281616144 10724114432 1156542816 12.371 100 35 70 2 1405959552 240567712 8849024
11:59:50 11704 8192000000 10307094336 10761240576 1140722960 12.222 99 40 80 2 1409168944 258700416 9745568
12:01:13 14485 8192000000 10346462096 10774056960 1143869264 12.252 100 39 78 1 1439172992 285355536 9948192
12:02:52 15210 8192000000 10265183808 10741297152 1117675888 12.005 99 20 40 1 1408599376 284949376 5974112
12:04:11 7134 8192000000 10226384672 10672492544 1119820096 12.025 100 35 71 2 1344385200 216114080 8451024
12:05:23 52801 8192000000 10236505664 10665181184 1121285696 12.039 100 23 46 2 1334179088 206985648 5907744
12:07:30 19606 8192000000 10218335664 10744889344 1134257376 12.161 100 21 42 2 1345695200 206571680 4866144
12:08:51 38635 8192000000 10102130080 10588090368 1089535104 11.738 98 12 24 1 1203512576 111267472 2710000
12:10:38 27925 8192000000 10063006096 10471780352 1110631760 11.938 99 53 106 1 1138404752 16649056 11123936
12:12:14 50393 8192000000 10122228016 10546712576 1129475744 12.116 99 43 86 0 1211719984 72267776 9976464
12:14:10 72506 8192000000 10107069136 10556645376 1093745200 11.778 96 13 26 0 1170823152 73914560 3163392
12:16:23 114094 8192000000 10073732352 10532413440 1090571504 11.748 96 15 30 0 1133593264 40173856 2847904
12:19:22 88462 8192000000 10115774944 10589204480 1101374560 11.851 97 11 22 0 1182740432 78614208 2751664
12:21:55 53605 8192000000 10069373072 10507579392 1135123328 12.170 100 26 52 0 1175739728 34766592 5849808
12:23:56 32533 8192000000 10116329104 10543263744 1125076224 12.075 98 46 91 0 1186082320 51041040 9965056
12:25:30 49796 8192000000 10098411088 10548654080 1125236336 12.076 98 21 42 0 1192872960 63313264 4323360
12:27:22 46063 8192000000 10103755440 10557693952 1138329552 12.200 99 26 52 0 1210733936 66494160 5910224
12:29:14 10040 8192000000 10112008944 10527584256 1120643184 12.033 97 59 117 0 1175121920 41682528 12796208
12:30:26 37681 8192000000 10082129168 10561593344 1146840368 12.280 100 20 40 0 1214678384 63293616 4544400
12:32:21 43773 8192000000 10123278368 10595913728 1156109152 12.367 100 10 20 0 1233902368 75521200 2272016
12:34:07 88026 8192000000 10160908912 10626351104 1144245424 12.255 99 22 44 1 1248875328 99459024 5170880
12:36:35 68433 8192000000 10122903296 10624577536 1141534784 12.230 99 23 46 0 1228108272 81876160 4697328
12:38:45 63764 8192000000 10150815824 10628313088 1121416160 12.040 97 15 30 0 1219227552 94595696 3215696
12:40:50 58566 8192000000 10137115616 10576711680 1104630768 11.882 96 14 29 0 1220256144 112595440 3029936
12:42:48 39799 8192000000 10052187744 10478301184 1034056800 11.208 91 20 40 0 1066545872 28657632 3831440
12:44:31 43483 8192000000 10143804288 10575216640 1124358384 12.068 100 30 60 0 1219403952 88739488 6306080
12:46:55 40805 8192000000 10124058224 10577821696 1111661232 11.948 99 14 28 1 1207323504 92467168 3195104
12:48:36 81026 8192000000 10085809696 10565152768 1093448784 11.775 97 30 60 0 1160021200 60210928 6361488
12:51:01 42225 8192000000 10062363600 10505121792 1101831056 11.855 99 23 46 0 1161430944 54887888 4712000
12:52:45 100929 8192000000 10112014288 10563055616 1106803680 11.902 99 34 68 1 1187392656 73190192 7398784
12:55:46 39994 8192000000 10070938176 10506350592 1104753280 11.883 99 27 53 1 1159373856 48654816 5965760
12:57:33 63507 8192000000 10050294144 10497454080 1098823248 11.826 98 6 12 0 1132520688 32580048 1117392
12:59:37 70481 8192000000 10097091600 10541137920 1080323200 11.651 96 13 26 0 1165412448 82117520 2971728
13:01:52 73531 8192000000 10050011584 10525425664 1104668336 11.882 99 13 26 0 1149816016 42442320 2705360
13:04:06 46791 8192000000 10101401104 10535481344 1116934320 11.998 99 29 56 2 1185748912 62718208 6096384
13:06:03 45278 8192000000 10091387648 10547507200 1133081008 12.150 100 7 14 0 1187592560 53090048 1421504
13:07:48 42383 8192000000 10131044800 10590728192 1140301568 12.218 99 27 54 0 1226360976 80336864 5722544
13:09:38 75519 8192000000 10107233408 10563358720 1124684032 12.071 98 17 34 0 1188500496 60477584 3338880
13:11:53 33385 8192000000 10108101168 10581774336 1141896752 12.233 100 37 74 1 1205586304 56396144 7293408
13:13:32 96511 8192000000 10163970208 10642341888 1134774368 12.166 98 23 47 0 1238115136 98505936 4834832
13:16:09 101878 8192000000 10072829280 10507833344 1138474880 12.201 99 19 38 0 1169718848 26670416 4573552
13:18:52 46471 8192000000 10065711568 10534494208 1138116752 12.198 99 7 14 0 1176041424 36582096 1342576
13:20:53 54457 8192000000 10095373056 10560995328 1151975344 12.328 100 16 32 1 1196023872 40526768 3521760
13:22:50 94675 8192000000 10143267840 10594131968 1152526656 12.333 99 21 42 1 1230286208 73047552 4712000
13:25:29 81810 8192000000 10076611104 10580496384 1113685712 11.967 96 10 20 0 1157839776 41959952 2194112
13:27:56 41545 8192000000 10107372240 10556305408 1122649952 12.052 97 23 46 0 1185307184 57763920 4893312
13:29:40 56361 8192000000 10104407744 10559176704 1115089728 11.981 97 44 88 0 1164654816 39725968 9839120
13:31:38 64808 8192000000 10085135216 10614657024 1137353840 12.191 99 25 50 0 1253970336 111172576 5443920
13:33:55 31734 8192000000 10118485360 10568024064 1118167008 12.010 98 32 64 0 1195394448 70683472 6543968
13:35:30 29180 8192000000 10107999472 10543775744 1123751664 12.062 98 41 80 3 1197634976 65771392 8111920
13:37:03 39106 8192000000 9175108224 9438892032 306641088 3.608 27 5 10 0 335130960 27681680 808192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2021 13:39:45 to 25-10-2021 13:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-10-2021 13:40:01 to 25-10-2021 13:40:03.

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          230958
MON$OLDEST_ACTIVE               230959
MON$OLDEST_SNAPSHOT             230959
MON$NEXT_TRANSACTION            230959
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                       2077344
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2642 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2642 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:346: 
:347: mscs-test-161.budzdorov.ru    Mon Oct 25 13:37:44 2021
:348:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:349: 
--- end of diff output ---

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

25.10.21 13:40:04. 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-10-25 13:40:06 No severe PSQL-related problems occured
25.10.21 13:40:07. End of report.