Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2022 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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16136500 kB
MemAvailable 31332120 kB
Buffers 0 kB
Cached 15586980 kB
SwapCached 2332 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 2022-01-12 13:37:07
DTS_END                         2022-01-12 13:37:07.8870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60494
TRN_ID                          214253
ATT_ID                          641
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: 9051.24 23621 1086149
customer order: creation 166.83 499 20020
customer order: refuse 14.32 462 1718
order to supplier: creation 43.73 1440 5248
order to supplier: removal 3.37 200972 404
invoice (draft): creation 44.14 3115 5297
invoice (draft): removal 49.92 1182 5990
invoice accept: apply 40.92 30961 4910
invoice accept: cancel 45.26 1376 5431
customer reserve: creation 3914.78 288 469773
customer reserve: removal 4441.88 119 533026
realization accept: apply 80.29 288 9635
realization accept: cancel 78.08 185 9370
payment from customer: creation 57.43 169 6891
payment from customer: removal 5.53 138 664
payment to supplier: creation 48.59 183 5831
payment to supplier: removal 3.26 117 391
service: total inventory turnovers 4.21 4272 505
service: total monetary turnovers 7.61 350 913
service: aggregate perf. data 1.07 14600 128
service: refresh index statistics 0.03 211699 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2022 13:39:53 to 12-01-2022 13:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8899
TEST_TIME 2 7758
TEST_TIME 3 7216
TEST_TIME 4 6359
TEST_TIME 5 6159
TEST_TIME 6 5881
TEST_TIME 7 5696
TEST_TIME 8 5459
TEST_TIME 9 6071
TEST_TIME 10 6222
TEST_TIME 11 6398
TEST_TIME 12 6550
TEST_TIME 13 6830
TEST_TIME 14 6934
TEST_TIME 15 6786
TEST_TIME 16 6739
TEST_TIME 17 6767
TEST_TIME 18 6744
TEST_TIME 19 6847
TEST_TIME 20 6909
TEST_TIME 21 6932
TEST_TIME 22 7168
TEST_TIME 23 7051
TEST_TIME 24 7196
TEST_TIME 25 7191
TEST_TIME 26 7234
TEST_TIME 27 7194
TEST_TIME 28 8033
TEST_TIME 29 8237
TEST_TIME 30 8665
TEST_TIME 31 9244
TEST_TIME 32 9510
TEST_TIME 33 9741
TEST_TIME 34 10273
TEST_TIME 35 10375
TEST_TIME 36 10578
TEST_TIME 37 10698
TEST_TIME 38 10780
TEST_TIME 39 10791
TEST_TIME 40 10702
TEST_TIME 41 10557
TEST_TIME 42 10555
TEST_TIME 43 10461
TEST_TIME 44 10451
TEST_TIME 45 10300
TEST_TIME 46 10224
TEST_TIME 47 10118
TEST_TIME 48 10038
TEST_TIME 49 9939
TEST_TIME 50 9893
TEST_TIME 51 9883
TEST_TIME 52 9778
TEST_TIME 53 9771
TEST_TIME 54 9813
TEST_TIME 55 9745
TEST_TIME 56 9642
TEST_TIME 57 9655
TEST_TIME 58 9601
TEST_TIME 59 9671
TEST_TIME 60 9785
TEST_TIME 61 9917
TEST_TIME 62 9955
TEST_TIME 63 9857
TEST_TIME 64 9884
TEST_TIME 65 9886
TEST_TIME 66 9861
TEST_TIME 67 9829
TEST_TIME 68 9914
TEST_TIME 69 9843
TEST_TIME 70 9839
TEST_TIME 71 9836
TEST_TIME 72 9841
TEST_TIME 73 9835
TEST_TIME 74 9724
TEST_TIME 75 9694
TEST_TIME 76 9709
TEST_TIME 77 9634
TEST_TIME 78 9592
TEST_TIME 79 9596
TEST_TIME 80 9545
TEST_TIME 81 9486
TEST_TIME 82 9440
TEST_TIME 83 9392
TEST_TIME 84 9339
TEST_TIME 85 9325
TEST_TIME 86 9338
TEST_TIME 87 9406
TEST_TIME 88 9397
TEST_TIME 89 9384
TEST_TIME 90 9360
TEST_TIME 91 9364
TEST_TIME 92 9415
TEST_TIME 93 9430
TEST_TIME 94 9474
TEST_TIME 95 9509
TEST_TIME 96 9533
TEST_TIME 97 9500
TEST_TIME 98 9446
TEST_TIME 99 9434
TEST_TIME 100 9353
TEST_TIME 101 9316
TEST_TIME 102 9287
TEST_TIME 103 9271
TEST_TIME 104 9275
TEST_TIME 105 9220
TEST_TIME 106 9197
TEST_TIME 107 9200
TEST_TIME 108 9218
TEST_TIME 109 9188
TEST_TIME 110 9185
TEST_TIME 111 9190
TEST_TIME 112 9200
TEST_TIME 113 9214
TEST_TIME 114 9190
TEST_TIME 115 9196
TEST_TIME 116 9216
TEST_TIME 117 9198
TEST_TIME 118 9133
TEST_TIME 119 9124
TEST_TIME 120 9079

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-01-2022 13:39:56 to 12-01-2022 13:40:00.

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 1517736 1517736 0 0.00 0 14312 16 0 0
doc_list_biud 1517721 1517721 0 0.00 0 41069 48 0 0
sp_add_invoice_to_stock 5026 4910 116 2.30 36 270050 30962 0 116
sp_cancel_adding_invoice 5431 5431 0 0.00 7 120003 1377 0 0
sp_cancel_client_order 1718 1718 0 0.00 6 10079 463 0 0
sp_cancel_customer_reserve 533026 533026 0 0.00 1 41086 120 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 5392 139 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 4 2477 117 0 0
sp_cancel_supplier_invoice 5993 5990 3 0.05 3 22515 1182 0 3
sp_cancel_supplier_order 404 404 0 0.00 11 1577213 200972 0 0
sp_cancel_write_off 9370 9370 0 0.00 2 10645 185 0 0
sp_client_order 20020 20020 0 0.00 5 17703 499 0 0
sp_customer_reserve 469773 469773 0 0.00 0 149693 288 0 0
sp_fill_shopping_cart 30552 30552 0 0.00 1 11799 80 0 0
sp_fill_shopping_cart_clo_res 469734 469734 0 0.00 0 17148 23 0 0
sp_get_clo_for_invoice 4862 4862 0 0.00 0 37969 321 0 0
sp_kill_cost_storno 15856 15856 0 0.00 0 10345 80 0 0
sp_kill_qstorno_ret_qs2qd 1081816 1081816 0 0.00 0 41042 52 0 0
sp_kill_qty_storno 570434 570434 0 0.00 0 41067 118 0 0
sp_lock_selected_doc 20030 20030 0 0.00 0 10122 9 0 0
sp_make_cost_storno 27217 27217 0 0.00 0 14298 82 0 0
sp_multiply_rows_for_pdistr 27217 27217 0 0.00 0 10292 37 0 0
sp_multiply_rows_for_qdistr 20019 20019 0 0.00 0 9657 196 0 0
sp_pay_from_customer 6891 6891 0 0.00 5 12363 170 0 0
sp_pay_to_supplier 5831 5831 0 0.00 6 8417 183 0 0
sp_payment_common 12722 12722 0 0.00 2 10561 41 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 10049 379 0 0
sp_qd_handle_on_invoice_upd_sts 10291 10291 0 0.00 0 14902 564 0 0
sp_qd_handle_on_reserve_upd_sts 9635 9635 0 0.00 0 23117 72 0 0
sp_reserve_write_off 9635 9635 0 0.00 8 23244 289 0 0
sp_supplier_invoice 5297 5297 0 0.00 10 79834 3115 0 0
sp_supplier_order 5248 5248 0 0.00 9 32231 1440 0 0
srv_aggregate_perf_data 128 128 0 0.00 2681 183032 14601 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 786 163267 32752 0 0
srv_find_qd_qs_mism 1037211 1037211 0 0.00 0 11351 13 0 0
srv_make_invnt_saldo 505 505 0 0.00 71 69092 4273 0 0
srv_make_money_saldo 913 913 0 0.00 1 15409 351 0 0
srv_recalc_idx_stat 4 4 0 0.00 109021 330428 211700 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4914 4914 0 0.00 1 18333 968 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 2 7103 465 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 5623 38 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 2777 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 10325 37 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 4623 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 1614 39 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 117222 8247 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 2462 26 0 0
t$perf-norm:sp_client_order 20020 20020 0 0.00 0 7967 37 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 1937 20 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 17905 26 0 0
t$perf-norm:sp_pay_to_supplier 5831 5831 0 0.00 0 7951 21 0 0
t$perf-norm:sp_reserve_write_off 9635 9635 0 0.00 0 20359 24 0 0
t$perf-norm:sp_supplier_invoice 5297 5297 0 0.00 0 6221 34 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 6997 33 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 562 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 147 7 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 1763 17 0 0
t$perf-norm:srv_make_money_saldo 913 913 0 0.00 0 5129 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 192 82 0 0
v_add_invoice_to_stock 9942 9710 232 2.33 0 3489 24 0 232
v_all_customers 36720 36720 0 0.00 0 101 4 0 0
v_all_suppliers 30204 30204 0 0.00 0 163 4 0 0
v_all_wares 40034 40034 0 0.00 0 187 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 883 14 0 0
v_cancel_client_order 3436 3436 0 0.00 0 2734 12 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 5330 19 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 1 3522 16 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 2414 15 0 0
v_cancel_supplier_invoice 730 724 6 0.82 0 827 21 0 6
v_cancel_supplier_order 808 808 0 0.00 0 856 12 0 0
v_cancel_write_off 1896 1896 0 0.00 0 5363 27 0 0
v_max_id_clo_ord 5245 5245 0 0.00 0 4418 6 0 0
v_max_id_clo_res 981 981 0 0.00 0 1701 13 0 0
v_max_id_ord_sup 5290 5290 0 0.00 0 3399 8 0 0
v_max_non_paid_invoice 1264 1264 0 0.00 0 944 9 0 0
v_max_non_paid_realizn 3552 3552 0 0.00 0 640 7 0 0
v_min_id_clo_ord 5245 5245 0 0.00 0 3056 8 0 0
v_min_id_clo_res 981 981 0 0.00 0 1982 8 0 0
v_min_id_ord_sup 5290 5290 0 0.00 0 9053 12 0 0
v_min_non_paid_invoice 5831 5831 0 0.00 0 3366 12 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 10555 14 0 0
v_our_firm 9992 9992 0 0.00 0 121 4 0 0
v_reserve_write_off 19270 19270 0 0.00 0 3522 10 0 0
x_lock_depdocs_on_canc_invoice 11059 11059 0 0.00 0 17572 135 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 3193 364 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 2 32210 1267 0 0
x_make_qstorno_1200_2000 5297 5297 0 0.00 4 79792 2899 0 0
x_make_qstorno_2100_3300 469771 469771 0 0.00 0 149675 252 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2022 13:40:01 to 12-01-2022 13:40:05.

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:11 13 8192000000 8806799664 8821137408 9568416 0.116 4 1 2 0 9700400 88192 43792
11:08:13 21136 8192000000 9732749040 10030292992 811847248 9.016 100 88 175 0 840076192 7613776 20615168
11:09:40 25113 8192000000 9763819808 10070683648 838547648 9.285 100 89 178 0 872367392 13058176 20761568
11:11:11 19680 8192000000 9798588208 10119933952 868585712 9.586 100 77 154 0 912422752 26520080 17316960
11:12:33 10261 8192000000 9868253152 10198769664 916656256 10.063 100 80 160 1 972104096 37981248 17466592
11:13:45 5400 8192000000 9908992080 10253135872 955319296 10.443 100 78 156 0 1021725344 50067824 16338224
11:14:53 1645 8192000000 9959126912 10308366336 992828928 10.809 100 96 188 0 1078862688 66637728 19396032
11:15:57 2106 8192000000 9993683728 10351067136 1019905872 11.071 100 92 178 1 1107356176 69068384 18381920
11:17:00 1001 8192000000 10008550544 10380337152 1036059152 11.227 100 94 180 1 1127639456 72956304 18624000
11:18:03 1335 8192000000 10026917472 10403889152 1056811376 11.426 100 92 177 2 1149149504 74422144 17915984
11:19:06 1842 8192000000 10047619792 10423844864 1065022592 11.505 100 97 191 2 1164243040 80173952 19046496
11:20:10 1426 8192000000 10041010064 10417840128 1071440000 11.566 100 94 184 2 1160372944 70131392 18801552
11:21:12 1077 8192000000 10039482896 10418118656 1074360112 11.594 100 92 171 5 1158512464 67518656 16633696
11:22:14 1372 8192000000 10057143392 10439061504 1080102944 11.648 100 96 185 1 1176301520 77564000 18634576
11:23:18 1422 8192000000 10049450752 10435649536 1086546208 11.710 100 77 147 2 1175076192 72242720 16287264
11:24:22 1193 8192000000 10068041328 10454212608 1093907008 11.780 100 93 185 0 1186028704 73843648 18278048
11:25:24 1050 8192000000 10065290736 10451849216 1101900560 11.856 100 90 176 4 1187630992 67505712 18224720
11:26:26 1267 8192000000 10056173584 10444656640 1102719360 11.863 100 92 182 1 1180227008 58532064 18975584
11:27:28 1204 8192000000 10077970144 10466263040 1105140720 11.886 100 97 185 2 1201525520 76799056 19585744
11:28:30 1077 8192000000 10048740656 10431651840 1096532928 11.805 99 94 181 3 1165620448 49880416 19207104
11:29:32 908 8192000000 10065917392 10455576576 1109848560 11.931 100 86 162 1 1191081744 63234272 17998912
11:30:34 5696 8192000000 10037936960 10426830848 1086073392 11.705 100 74 136 3 1160187040 61177344 12936304
11:31:42 1599 8192000000 10012921792 10393587712 1055104208 11.410 99 83 155 5 1134384672 63014576 16265888
11:32:45 1211 8192000000 10066671520 10447048704 1072164000 11.573 100 96 186 1 1192518672 101378416 18976256
11:33:46 1103 8192000000 9987408464 10353930240 1021759808 11.089 100 90 170 2 1111733568 72848416 17125344
11:34:49 1193 8192000000 9987750544 10357624832 1017442800 11.047 99 94 174 0 1108181744 72248096 18490848
11:35:50 1465 8192000000 9975947568 10344730624 1029043904 11.159 100 73 142 0 1094781088 51036656 14700528
11:36:53 654 8192000000 9989711632 10359832576 1023683328 11.108 100 87 164 1 1114844736 73430912 17730496
11:37:57 8060 8192000000 10008389920 10383470592 1051627008 11.376 100 72 135 0 1127572720 61619184 14326528
11:39:15 2394 8192000000 10051948064 10427342848 1070970672 11.561 100 78 150 0 1175720624 89664256 15085696
11:40:24 13317 8192000000 10045702752 10429616128 1081978592 11.666 100 70 136 2 1169968304 75488624 12501088
11:41:41 25840 8192000000 10088136048 10486661120 1099778512 11.836 100 63 120 0 1207897344 94286704 13832128
11:43:11 9450 8192000000 10120607328 10517823488 1116633536 11.995 100 57 113 1 1233589824 104031408 12924880
11:44:24 5574 8192000000 10174036992 10568003584 1125094016 12.075 100 71 139 0 1290398016 149433680 15870320
11:45:36 6353 8192000000 10217767648 10621927424 1134640400 12.165 100 68 132 0 1333146032 183503360 15002272
11:46:45 4530 8192000000 10227146672 10624843776 1150543488 12.315 100 91 174 1 1339614304 169645616 19425200
11:47:54 1908 8192000000 10253539520 10648633344 1164150736 12.442 100 83 163 1 1364783472 182553360 18079376
11:48:59 3675 8192000000 10290211472 10696454144 1163973264 12.440 100 90 180 0 1403313904 218889072 20451568
11:50:08 1122 8192000000 10285807904 10705915904 1175505376 12.548 100 92 177 0 1395702752 200221024 19976352
11:51:12 2354 8192000000 10347585072 10751619072 1188112992 12.666 100 84 168 0 1463736224 256951040 18672192
11:52:18 1159 8192000000 10387426064 10787512320 1205221312 12.825 100 97 187 1 1495614048 269190880 21201856
11:53:23 1307 8192000000 10423479520 10822615040 1203892192 12.812 100 91 173 2 1531494928 306766496 20836240
11:54:30 1307 8192000000 10486417280 10896531456 1207178752 12.843 100 96 183 0 1593658496 365115328 21364416
11:55:36 1998 8192000000 10527044192 10942349312 1220310960 12.965 100 96 189 0 1633953408 391027424 22615024
11:56:42 882 8192000000 10559449680 10974507008 1232524176 13.077 100 96 188 1 1669427248 414027408 22875664
11:57:47 783 8192000000 10612792000 11054260224 1235988720 13.109 100 98 195 1 1716995952 457495440 23511792
11:58:52 1532 8192000000 10624977376 11060428800 1214233392 12.908 100 94 188 1 1727063440 489470512 23359536
11:59:58 1598 8192000000 10642448576 11061743616 1211788704 12.886 100 86 172 1 1747318016 513585216 21944096
12:01:05 779 8192000000 10669362704 11108220928 1200770272 12.783 100 90 180 0 1772105280 548949328 22385680
12:02:10 4656 8192000000 10704728464 11140698112 1208171776 12.852 100 87 169 1 1804579040 574893232 21514032
12:03:21 960 8192000000 10777635776 11206582272 1212902304 12.896 100 93 184 0 1880608160 644259376 23446480
12:04:24 1167 8192000000 10727657872 11235893248 1205526576 12.828 100 81 160 1 1833276288 606857664 20892048
12:05:29 2693 8192000000 10681317344 11190530048 1201994048 12.795 100 60 119 0 1782168944 564311616 15863280
12:06:42 7038 8192000000 10614527984 11110170624 1183260912 12.621 100 53 106 1 1713388752 515918192 14209648
12:07:58 22403 8192000000 10507080992 10983235584 1156173664 12.367 98 35 68 1 1626727712 461419824 9134224
12:09:41 19915 8192000000 10377519280 10846302208 1149912368 12.309 99 33 64 3 1478603136 320805840 7884928
12:11:05 22262 8192000000 10242981856 10758189056 1139390160 12.210 99 31 61 0 1349950816 202845840 7714816
12:12:40 2181 8192000000 10204089216 10665431040 1140979472 12.225 100 53 98 2 1306345232 154481376 10884384
12:13:44 32216 8192000000 10153707392 10598100992 1140642336 12.222 100 34 62 2 1239870832 92132016 7096480
12:15:18 50777 8192000000 10108354768 10562990080 1135478848 12.173 99 13 24 1 1185979360 47855296 2645216
12:17:32 42182 8192000000 10107732592 10582351872 1149427168 12.304 100 29 47 1 1199885216 45423760 5034288
12:19:23 131168 8192000000 10088651424 10560040960 1127681408 12.099 98 29 58 1 1206047216 72247488 6118320
12:23:43 44893 8192000000 10079384080 10507018240 1085993808 11.705 95 59 113 2 1127059120 28531840 12533472
12:25:34 76498 8192000000 10081533568 10515996672 1102377136 11.860 97 27 52 0 1158393952 50925728 5091088
12:27:52 11636 8192000000 10134171664 10575572992 1128142928 12.104 99 45 87 1 1236175488 98350192 9682368
12:29:06 21252 8192000000 10157688912 10585739264 1142197536 12.236 99 46 88 4 1248285392 94703648 11384208
12:30:28 21575 8192000000 10212132528 10645446656 1139407664 12.210 99 63 120 1 1317590096 164320928 13861504
12:31:51 163239 8192000000 10244524016 10748002304 1138510064 12.202 98 22 44 1 1315229520 171737296 4982160
12:35:34 47328 8192000000 10252781632 10717790208 1138808688 12.204 100 29 58 0 1354403744 208429376 7165680
12:37:41 76441 8192000000 10091406960 10564014080 1128395680 12.106 99 20 41 0 1254428528 121294944 4737904
12:39:58 41844 8192000000 10108164400 10542239744 1129493360 12.117 99 35 69 1 1171911568 35909856 6508352
12:41:43 7791 8192000000 10096329504 10541010944 1139651904 12.212 100 69 135 0 1194862928 41687440 13523584
12:42:53 37910 8192000000 10112978528 10537222144 1130872608 12.130 99 38 74 0 1192816352 54163648 7780096
12:44:33 20603 8192000000 10106852544 10537934848 1125268912 12.077 99 35 70 1 1194278176 62099552 6909712
12:45:59 25796 8192000000 10051914272 10493943808 1126284336 12.086 100 20 40 1 1153381792 23313488 3783968
12:47:26 28782 8192000000 10078247168 10524700672 1120220560 12.029 99 35 69 0 1177462656 50794560 6447536
12:48:56 54643 8192000000 10073093312 10496086016 1109816048 11.931 98 34 68 0 1170050848 53508048 6726752
12:50:53 34585 8192000000 10125725744 10571083776 1130623584 12.127 100 40 79 3 1218072976 79687968 7761424
12:52:37 50333 8192000000 10079678352 10535157760 1109300720 11.926 98 27 54 0 1178174560 63027872 5845968
12:54:28 37058 8192000000 10075852960 10514620416 1131514896 12.136 100 47 93 1 1176281280 35381456 9384928
12:56:07 81585 8192000000 10048176352 10505555968 1084304512 11.688 96 17 34 0 1133814064 45501424 4008128
12:58:33 27890 8192000000 10103401232 10554028032 1134754512 12.166 100 30 60 1 1205602192 64049680 6798000
13:00:11 44545 8192000000 10093989088 10564640768 1127137184 12.094 100 27 49 2 1195556496 62933856 5485456
13:02:15 97326 8192000000 10086037680 10561875968 1087092192 11.715 96 21 42 1 1172227568 80235232 4900144
13:04:53 79135 8192000000 10120066416 10574819328 1114440320 11.974 98 18 35 2 1191929168 73684592 3804256
13:07:32 29267 8192000000 10154233920 10587381760 1142563136 12.240 100 27 52 2 1246493328 97844368 6085824
13:09:02 45217 8192000000 10130753088 10594377728 1133652688 12.156 99 35 70 2 1235559504 94011616 7895200
13:10:47 92514 8192000000 10111752944 10615599104 1098133920 11.820 96 27 54 0 1213040032 109376192 5529920
13:13:20 42615 8192000000 10092575584 10537259008 1124478544 12.069 99 24 46 0 1197962304 68741424 4742336
13:15:04 5939 8192000000 10118237104 10526449664 1135723456 12.175 100 86 164 2 1205970576 54004880 16242240
13:16:10 88297 8192000000 10086794128 10525573120 1130424672 12.125 99 35 70 0 1201959280 64559568 6975040
13:18:46 48355 8192000000 10105818880 10560122880 1117992400 12.008 99 30 58 0 1197028128 72946592 6089136
13:20:39 49253 8192000000 10048174544 10552684544 1094536464 11.786 98 12 24 0 1165090912 67229456 3324992
13:22:29 73141 8192000000 10077833024 10541318144 1114076416 11.971 99 16 32 1 1166349808 48465168 3808224
13:24:45 25314 8192000000 10078174816 10512396288 1127122944 12.094 100 37 74 0 1173824704 38676800 8024960
13:26:10 47167 8192000000 10100039616 10554064896 1118528896 12.013 99 31 62 3 1184420992 59617504 6274592
13:28:18 47034 8192000000 10101788928 10564763648 1093482416 11.776 97 39 77 0 1163831808 62870608 7478784
13:30:07 55165 8192000000 10085271712 10555207680 1100469296 11.842 98 25 48 1 1165142224 59457600 5215328
13:32:05 39390 8192000000 10080296304 10518257664 1092616048 11.768 97 40 80 1 1164410992 63714272 8080672
13:33:50 42212 8192000000 10065587952 10481623040 1088754640 11.731 98 45 89 1 1151177408 53642928 8779840
13:35:36 91494 8192000000 10055113568 10535030784 1116331328 11.992 100 29 58 0 1198813488 76620848 5861312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-01-2022 13:40:07 to 12-01-2022 13:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2022 13:40:24 to 12-01-2022 13:40:26.

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          214252
MON$OLDEST_ACTIVE               214459
MON$OLDEST_SNAPSHOT             214459
MON$NEXT_TRANSACTION            214459
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                       2074592
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:372: 
:373: mscs-test-161.budzdorov.ru    Wed Jan 12 13:37:18 2022
:374:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:375: 
:376: 
:377: mscs-test-161.budzdorov.ru    Wed Jan 12 13:37:38 2022
:378:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:379: 
:380: 
:381: mscs-test-161.budzdorov.ru    Wed Jan 12 13:37:38 2022
:382:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:383: 
:384: 
:385: mscs-test-161.budzdorov.ru    Wed Jan 12 13:37:39 2022
:386:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:387: 
--- end of diff output ---

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

12.01.22 13:40:27. 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
2022-01-12 13:40:30 No severe PSQL-related problems occured
12.01.22 13:40:31. End of report.