Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2022 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16096428 kB
MemAvailable 31229524 kB
Buffers 0 kB
Cached 15613384 kB
SwapCached 2480 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.2
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-06-22 13:37:20
DTS_END                         2022-06-22 13:37:20.2980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46928
TRN_ID                          216470
ATT_ID                          617
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: 8841.08 24448 1060929
customer order: creation 163.73 501 19648
customer order: refuse 13.84 376 1661
order to supplier: creation 43.18 1305 5182
order to supplier: removal 3.32 160759 398
invoice (draft): creation 43.99 3133 5279
invoice (draft): removal 50.92 932 6110
invoice accept: apply 39.10 25199 4692
invoice accept: cancel 46.04 1165 5525
customer reserve: creation 3634.25 240 436110
customer reserve: removal 4520.17 94 542420
realization accept: apply 80.22 264 9626
realization accept: cancel 79.39 162 9527
payment from customer: creation 56.69 177 6803
payment from customer: removal 4.84 111 581
payment to supplier: creation 46.66 180 5599
payment to supplier: removal 3.13 124 376
service: total inventory turnovers 3.38 4447 405
service: total monetary turnovers 7.34 375 881
service: aggregate perf. data 0.85 19586 102
service: refresh index statistics 0.03 269835 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 13:38:42 to 22-06-2022 13:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7838
TEST_TIME 2 7308
TEST_TIME 3 7061
TEST_TIME 4 7035
TEST_TIME 5 6476
TEST_TIME 6 6446
TEST_TIME 7 6609
TEST_TIME 8 6708
TEST_TIME 9 6750
TEST_TIME 10 6781
TEST_TIME 11 6893
TEST_TIME 12 6900
TEST_TIME 13 7401
TEST_TIME 14 7348
TEST_TIME 15 7617
TEST_TIME 16 7924
TEST_TIME 17 7996
TEST_TIME 18 8098
TEST_TIME 19 8064
TEST_TIME 20 7945
TEST_TIME 21 7847
TEST_TIME 22 7977
TEST_TIME 23 7966
TEST_TIME 24 8517
TEST_TIME 25 8619
TEST_TIME 26 8925
TEST_TIME 27 9248
TEST_TIME 28 9448
TEST_TIME 29 9922
TEST_TIME 30 9965
TEST_TIME 31 10569
TEST_TIME 32 11156
TEST_TIME 33 11240
TEST_TIME 34 10975
TEST_TIME 35 10741
TEST_TIME 36 10647
TEST_TIME 37 10530
TEST_TIME 38 10370
TEST_TIME 39 10300
TEST_TIME 40 10258
TEST_TIME 41 10328
TEST_TIME 42 10271
TEST_TIME 43 10137
TEST_TIME 44 10119
TEST_TIME 45 9932
TEST_TIME 46 9860
TEST_TIME 47 9794
TEST_TIME 48 9677
TEST_TIME 49 9578
TEST_TIME 50 9523
TEST_TIME 51 9463
TEST_TIME 52 9460
TEST_TIME 53 9404
TEST_TIME 54 9359
TEST_TIME 55 9264
TEST_TIME 56 9373
TEST_TIME 57 9442
TEST_TIME 58 9390
TEST_TIME 59 9329
TEST_TIME 60 9310
TEST_TIME 61 9318
TEST_TIME 62 9382
TEST_TIME 63 9382
TEST_TIME 64 9450
TEST_TIME 65 9598
TEST_TIME 66 9590
TEST_TIME 67 9642
TEST_TIME 68 9642
TEST_TIME 69 9569
TEST_TIME 70 9508
TEST_TIME 71 9445
TEST_TIME 72 9432
TEST_TIME 73 9338
TEST_TIME 74 9330
TEST_TIME 75 9354
TEST_TIME 76 9299
TEST_TIME 77 9230
TEST_TIME 78 9171
TEST_TIME 79 9122
TEST_TIME 80 9155
TEST_TIME 81 9124
TEST_TIME 82 9292
TEST_TIME 83 9241
TEST_TIME 84 9241
TEST_TIME 85 9234
TEST_TIME 86 9189
TEST_TIME 87 9178
TEST_TIME 88 9291
TEST_TIME 89 9355
TEST_TIME 90 9321
TEST_TIME 91 9364
TEST_TIME 92 9296
TEST_TIME 93 9268
TEST_TIME 94 9241
TEST_TIME 95 9190
TEST_TIME 96 9178
TEST_TIME 97 9156
TEST_TIME 98 9107
TEST_TIME 99 9108
TEST_TIME 100 9106
TEST_TIME 101 9131
TEST_TIME 102 9089
TEST_TIME 103 9140
TEST_TIME 104 9137
TEST_TIME 105 9160
TEST_TIME 106 9167
TEST_TIME 107 9140
TEST_TIME 108 9168
TEST_TIME 109 9210
TEST_TIME 110 9193
TEST_TIME 111 9114
TEST_TIME 112 9055
TEST_TIME 113 9058
TEST_TIME 114 9018
TEST_TIME 115 8989
TEST_TIME 116 8944
TEST_TIME 117 8910
TEST_TIME 118 8906
TEST_TIME 119 8917
TEST_TIME 120 8864

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-06-2022 13:38:45 to 22-06-2022 13:38:49.

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 1463420 1463420 0 0.00 0 43715 12 0 0
doc_list_biud 1463416 1463416 0 0.00 0 58261 40 0 0
sp_add_invoice_to_stock 4908 4692 216 4.40 47 260494 25200 0 216
sp_cancel_adding_invoice 5525 5525 0 0.00 7 66474 1165 0 0
sp_cancel_client_order 1661 1661 0 0.00 5 14806 376 0 0
sp_cancel_customer_reserve 542420 542420 0 0.00 1 58264 94 0 0
sp_cancel_pay_from_customer 581 581 0 0.00 3 3048 112 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 4 3698 125 0 0
sp_cancel_supplier_invoice 6129 6110 19 0.31 3 19083 932 0 19
sp_cancel_supplier_order 398 398 0 0.00 9 1484296 160760 0 0
sp_cancel_write_off 9527 9527 0 0.00 2 10669 162 0 0
sp_client_order 19648 19648 0 0.00 6 46399 501 0 0
sp_customer_reserve 436110 436110 0 0.00 1 136073 241 0 0
sp_fill_shopping_cart 30098 30098 0 0.00 1 9062 78 0 0
sp_fill_shopping_cart_clo_res 436077 436077 0 0.00 0 14174 20 0 0
sp_get_clo_for_invoice 4656 4656 0 0.00 0 22272 343 0 0
sp_kill_cost_storno 16009 16009 0 0.00 0 10655 70 0 0
sp_kill_qstorno_ret_qs2qd 1100875 1100875 0 0.00 0 58258 42 0 0
sp_kill_qty_storno 579921 579921 0 0.00 0 58261 94 0 0
sp_lock_selected_doc 19723 19723 0 0.00 0 9817 8 0 0
sp_make_cost_storno 26684 26684 0 0.00 0 17012 77 0 0
sp_multiply_rows_for_pdistr 26683 26683 0 0.00 0 9958 38 0 0
sp_multiply_rows_for_qdistr 19648 19648 0 0.00 0 27079 197 0 0
sp_pay_from_customer 6803 6803 0 0.00 6 11317 178 0 0
sp_pay_to_supplier 5599 5599 0 0.00 6 20959 180 0 0
sp_payment_common 12401 12401 0 0.00 2 9026 40 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 1 14674 310 0 0
sp_qd_handle_on_invoice_upd_sts 10180 10180 0 0.00 0 18688 469 0 0
sp_qd_handle_on_reserve_upd_sts 9625 9625 0 0.00 0 16707 61 0 0
sp_reserve_write_off 9626 9626 0 0.00 9 19518 264 0 0
sp_supplier_invoice 5279 5279 0 0.00 11 83422 3133 0 0
sp_supplier_order 5182 5182 0 0.00 7 48744 1305 0 0
srv_aggregate_perf_data 102 102 0 0.00 2674 340468 19586 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 833 213325 40468 0 0
srv_find_qd_qs_mism 1014997 1014997 0 0.00 0 43712 9 0 0
srv_make_invnt_saldo 405 405 0 0.00 53 72289 4448 0 0
srv_make_money_saldo 881 881 0 0.00 1 18338 376 0 0
srv_recalc_idx_stat 4 4 0 0.00 92585 473025 269836 0 0
t$perf-abend:sp_add_invoice_to_stock 216 216 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4695 4695 0 0.00 1 12419 830 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 7130 463 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 2989 25 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2037 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 581 581 0 0.00 0 775 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 2832 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1062 37 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 70265 6238 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 2925 19 0 0
t$perf-norm:sp_client_order 19649 19649 0 0.00 0 5270 32 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 2529 16 0 0
t$perf-norm:sp_pay_from_customer 6803 6803 0 0.00 0 9582 19 0 0
t$perf-norm:sp_pay_to_supplier 5599 5599 0 0.00 0 6285 20 0 0
t$perf-norm:sp_reserve_write_off 9626 9626 0 0.00 0 8146 23 0 0
t$perf-norm:sp_supplier_invoice 5279 5279 0 0.00 0 5902 28 0 0
t$perf-norm:sp_supplier_order 5182 5182 0 0.00 0 5563 32 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 642 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 846 22 0 0
t$perf-norm:srv_make_invnt_saldo 405 405 0 0.00 0 2650 22 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 5230 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 163 69 0 0
v_add_invoice_to_stock 9734 9302 432 4.43 0 10467 29 0 432
v_all_customers 35914 35914 0 0.00 0 64 3 0 0
v_all_suppliers 29360 29360 0 0.00 0 70 4 0 0
v_all_wares 39292 39292 0 0.00 0 90 3 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2765 20 0 0
v_cancel_client_order 3322 3322 0 0.00 0 3141 10 0 0
v_cancel_customer_prepayment 1162 1162 0 0.00 0 1534 12 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 1 1530 13 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 3602 15 0 0
v_cancel_supplier_invoice 866 828 38 4.38 0 2853 31 0 38
v_cancel_supplier_order 796 796 0 0.00 0 1096 10 0 0
v_cancel_write_off 1912 1912 0 0.00 0 2995 26 0 0
v_max_id_clo_ord 5179 5179 0 0.00 0 258 4 0 0
v_max_id_clo_res 939 939 0 0.00 0 1226 11 0 0
v_max_id_ord_sup 5273 5273 0 0.00 0 6957 11 0 0
v_max_non_paid_invoice 1370 1370 0 0.00 0 1354 8 0 0
v_max_non_paid_realizn 3578 3578 0 0.00 0 5684 10 0 0
v_min_id_clo_ord 5179 5179 0 0.00 0 4480 7 0 0
v_min_id_clo_res 939 939 0 0.00 0 1582 10 0 0
v_min_id_ord_sup 5273 5273 0 0.00 0 4853 8 0 0
v_min_non_paid_invoice 5598 5598 0 0.00 0 2828 11 0 0
v_min_non_paid_realizn 6803 6803 0 0.00 0 9016 16 0 0
v_our_firm 9828 9828 0 0.00 0 82 4 0 0
v_reserve_write_off 19250 19250 0 0.00 0 4908 12 0 0
x_lock_depdocs_on_canc_invoice 11221 11221 0 0.00 0 23348 128 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 32711 443 0 0
x_make_qstorno_1000_1200 5182 5182 0 0.00 3 47902 1151 0 0
x_make_qstorno_1200_2000 5279 5279 0 0.00 4 83400 2919 0 0
x_make_qstorno_2100_3300 436109 436109 0 0.00 0 136045 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 13:38:50 to 22-06-2022 13:38:54.

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:24 1 8192000000 8797168096 8807763968 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:25 30357 8192000000 9740886800 10045501440 814185024 9.040 100 86 172 0 841976736 7431984 20359728
11:10:00 26027 8192000000 9773488112 10086223872 848797792 9.388 100 89 178 0 882571296 12288352 21485152
11:11:29 23990 8192000000 9820408912 10146447360 890501968 9.804 100 84 168 0 931464128 21677968 19284192
11:12:57 5384 8192000000 9870390288 10204520448 925714384 10.152 100 81 162 1 979079968 36090560 17275024
11:14:06 4395 8192000000 9955010624 10299777024 974676768 10.632 100 90 179 2 1060650496 67523776 18449952
11:15:12 2265 8192000000 9971450816 10329051136 1012239152 10.997 100 91 176 1 1094215440 64604864 17371424
11:16:17 1013 8192000000 10013155632 10376765440 1033965376 11.207 100 95 181 0 1130617888 79012752 17639760
11:17:20 1252 8192000000 10029673648 10404249600 1050542656 11.366 100 94 182 1 1145546544 76204144 18799744
11:18:22 809 8192000000 10059676272 10438815744 1065796832 11.512 100 96 183 0 1175291184 90696768 18797584
11:19:24 1190 8192000000 10041332800 10418532352 1075385200 11.603 100 99 190 2 1161500160 66898160 19216800
11:20:26 1390 8192000000 10046430448 10435366912 1078560640 11.634 100 76 148 0 1180002208 86594368 14847200
11:21:29 1145 8192000000 10071289488 10459668480 1080872816 11.656 100 98 195 0 1193372944 92460848 20039280
11:22:30 1436 8192000000 10040026816 10426134528 1088181232 11.725 100 94 173 1 1161553072 55202848 18168992
11:23:33 952 8192000000 10048149488 10437644288 1093213088 11.773 100 92 181 2 1165666512 54214848 18238576
11:24:35 1028 8192000000 10084135920 10480214016 1100001168 11.838 100 92 179 1 1206174480 88820768 17352544
11:25:37 1369 8192000000 10057017232 10445819904 1107360688 11.907 100 93 177 1 1175359056 50000816 17997552
11:26:39 1205 8192000000 10077285504 10469376000 1105201040 11.887 100 95 186 2 1200465376 77324848 17939488
11:27:42 4784 8192000000 10022550848 10413113344 1082901440 11.675 99 66 127 0 1146384240 51046128 12436672
11:28:47 1203 8192000000 10061851936 10449395712 1091711952 11.759 100 96 173 0 1187761616 77341520 18708144
11:29:49 1869 8192000000 10011888544 10403323904 1080539232 11.653 100 79 155 2 1141486000 44156704 16790064
11:30:54 1349 8192000000 10068469888 10446925824 1082460432 11.671 100 98 191 2 1189457632 87389968 19607232
11:31:57 1173 8192000000 10043315920 10428215296 1070554304 11.557 100 86 171 1 1165138672 76798624 17785744
11:32:58 1596 8192000000 10002641584 10375680000 1040733392 11.272 99 92 173 3 1124892656 67545856 16613408
11:34:01 915 8192000000 10002710192 10379206656 1049555440 11.356 100 93 180 0 1129907296 61450272 18901584
11:35:03 1194 8192000000 9980297072 10351468544 1032942928 11.197 99 93 168 1 1106195280 56618944 16633408
11:36:05 1131 8192000000 10007535216 10378874880 1037975856 11.245 100 94 184 1 1129527936 72890992 18661088
11:37:07 4196 8192000000 10026588160 10407899136 1053761440 11.397 100 74 134 0 1141172144 74279552 13131152
11:38:16 1236 8192000000 10018292912 10395418624 1072100480 11.572 100 93 183 0 1140514480 49918592 18495408
11:39:23 3039 8192000000 10059720144 10447278080 1095881328 11.799 100 76 147 0 1181784880 71596000 14307552
11:40:31 3883 8192000000 10087443280 10478493696 1103017744 11.866 100 72 143 0 1212107552 94098576 14991232
11:41:39 4698 8192000000 10128087648 10519379968 1115347408 11.983 100 90 175 0 1246583104 112184880 19050816
11:42:49 3162 8192000000 10139655472 10534596608 1122510896 12.051 100 74 140 0 1257234144 119063904 15659344
11:43:56 911 8192000000 10162919392 10563108864 1127445456 12.097 100 94 176 3 1277549472 130490816 19613200
11:45:00 6352 8192000000 10208401632 10612842496 1145290784 12.265 100 75 150 0 1315922144 154410864 16220496
11:46:09 1439 8192000000 10236082800 10630451200 1149799568 12.308 100 91 176 1 1349976608 181051904 19125136
11:47:15 8672 8192000000 10243213552 10643492864 1141818688 12.233 99 79 156 0 1361341184 201948528 17573968
11:48:27 3277 8192000000 10275891088 10682097664 1158625808 12.390 100 78 143 1 1390358672 214763776 16969088
11:49:33 821 8192000000 10294245744 10698235904 1162789760 12.429 100 98 190 1 1407914864 222411168 22713936
11:50:36 1090 8192000000 10311177680 10712182784 1165098192 12.451 100 96 188 5 1422566064 235108272 22359600
11:51:45 1353 8192000000 10351352064 10772254720 1174691456 12.541 100 94 183 0 1462031984 265956608 21383920
11:52:49 1000 8192000000 10345447488 10783014912 1188086704 12.666 100 87 166 1 1450762912 242803696 19872512
11:53:52 850 8192000000 10352967616 10757791744 1178376960 12.575 99 95 186 0 1463049728 263106896 21565872
11:54:58 1327 8192000000 10365384992 10785812480 1190333136 12.686 100 79 156 0 1471988704 261894752 19760816
11:56:03 5849 8192000000 10379058624 10805620736 1184338912 12.631 100 57 114 0 1483074976 284968848 13767216
11:57:13 1612 8192000000 10425598144 10846056448 1192712704 12.709 100 81 161 0 1534442560 323053440 18676416
11:58:17 880 8192000000 10481077328 10895425536 1188919968 12.673 100 97 191 2 1586365024 374162704 23282352
11:59:25 1745 8192000000 10533646928 10957873152 1196349200 12.742 100 95 180 0 1637328080 419364720 21614160
12:00:30 1683 8192000000 10514299856 10963791872 1175131008 12.545 100 80 155 2 1622154800 427469680 19554112
12:01:35 3701 8192000000 10484832064 10951094272 1162360608 12.425 99 71 130 2 1593653664 414883840 16409216
12:02:45 3365 8192000000 10476324912 10956759040 1182032544 12.609 100 59 111 2 1575396240 378916192 14447504
12:03:50 7960 8192000000 10528540480 10967511040 1186704544 12.653 100 50 94 1 1636868496 437668768 12495184
12:05:01 18321 8192000000 10478324080 10946420736 1178218576 12.574 100 31 62 0 1587075360 399738752 9118032
12:06:41 26824 8192000000 10338705424 10807832576 1138633264 12.203 97 28 55 1 1444121168 298724720 6763184
12:08:08 35321 8192000000 10210294816 10758221824 1078513664 11.633 94 21 42 0 1336238928 252468320 5256944
12:09:45 31985 8192000000 10060714144 10526679040 1129614064 12.118 100 13 26 1 1229427008 96700704 3112240
12:11:56 53519 8192000000 10130471632 10569687040 1132618080 12.146 98 40 80 0 1205035072 63992672 8424320
12:13:53 213324 8192000000 10157334464 10596315136 1082953808 11.676 94 22 42 0 1182682512 94663872 5064832
12:18:28 24908 8192000000 10052958784 10522996736 1099876848 11.836 97 24 47 0 1134849840 30024976 4948016
12:19:54 58931 8192000000 10103810896 10574278656 1111203264 11.944 98 7 14 0 1148445680 35660528 1581888
12:22:26 108404 8192000000 10083576608 10555666432 1138980368 12.206 100 30 60 0 1205969584 59960224 7028992
12:25:28 32815 8192000000 10074722832 10536636416 1147489872 12.286 100 15 30 1 1189061168 38365456 3205840
12:27:02 23518 8192000000 10113589600 10548633600 1121834240 12.044 98 43 87 1 1184700352 54213440 8652672
12:28:28 56068 8192000000 10110651936 10579894272 1112034752 11.952 98 16 32 1 1204457632 88792672 3630208
12:30:24 76796 8192000000 10126352144 10627489792 1148396912 12.294 100 16 32 0 1247282048 95302800 3582336
12:32:49 56675 8192000000 10109049488 10632843264 1131675312 12.137 99 9 18 0 1241436528 107572896 2188320
12:34:48 59332 8192000000 10056333184 10495623168 1110082432 11.933 97 33 62 2 1147975824 31104624 6788768
12:36:49 9754 8192000000 10108690432 10540929024 1133112224 12.151 100 58 114 1 1207004640 62366624 11525792
12:38:02 81590 8192000000 10149254896 10593890304 1118867120 12.016 97 16 32 0 1189374384 67146384 3360880
12:40:24 63473 8192000000 10158765280 10615291904 1140091696 12.216 99 22 44 1 1261612704 116324512 5196496
12:42:33 82624 8192000000 10127519664 10574442496 1128446848 12.107 98 14 28 0 1199047792 67697072 2903872
12:45:03 48216 8192000000 10119792752 10574442496 1127517984 12.098 98 34 68 1 1173377776 38641680 7218112
12:46:52 99490 8192000000 10095712080 10560200704 1129109104 12.113 99 50 100 0 1215773776 76670624 9994048
12:49:52 59035 8192000000 10130398464 10566422528 1128658512 12.109 99 36 67 3 1213845632 78270128 6916992
12:51:52 54775 8192000000 10080782800 10566774784 1128707680 12.109 99 16 31 0 1187215120 55493072 3014368
12:53:47 54387 8192000000 10121785008 10568814592 1115453840 11.984 98 16 32 0 1203632112 84665952 3512320
12:55:50 87269 8192000000 10128345440 10627104768 1126420752 12.088 99 18 36 0 1259407904 128540624 4446528
12:58:37 36929 8192000000 10146620528 10596622336 1110891072 11.941 97 23 45 2 1221988256 105920400 5176784
13:00:22 47603 8192000000 10091818848 10538110976 1128778704 12.110 100 31 62 1 1175664432 40809152 6076576
13:02:11 36949 8192000000 10137052016 10582331392 1136137600 12.179 99 35 69 2 1246861056 102761040 7962416
13:03:50 58474 8192000000 10193662144 10636115968 1120265440 12.029 98 38 76 1 1255233504 126546896 8421168
13:05:49 32357 8192000000 10094218416 10568159232 1102343072 11.860 98 17 34 0 1191967232 85948656 3675504
13:07:21 46784 8192000000 10132865744 10556936192 1104800128 11.883 97 40 80 0 1173912752 61368352 7744272
13:09:11 110211 8192000000 10063966832 10515038208 1094551840 11.786 97 34 66 0 1131026368 29401120 7073408
13:12:01 75436 8192000000 10071326928 10566426624 1116349504 11.992 99 8 16 0 1171255472 53213424 1692544
13:14:25 84821 8192000000 10096186816 10559324160 1116014240 11.989 98 23 45 0 1180148016 59300032 4833744
13:16:59 46650 8192000000 10096795488 10565468160 1120661840 12.033 98 15 30 0 1182745952 58800128 3283984
13:18:49 63821 8192000000 10115221232 10545311744 1089001968 11.733 95 35 68 1 1152949008 56369072 7577968
13:20:57 87673 8192000000 10102647440 10572517376 1079991328 11.647 95 20 40 1 1130885776 46818144 4076304
13:23:37 46211 8192000000 10093760000 10540417024 1091512496 11.757 96 28 56 0 1125030336 28069552 5448288
13:25:29 57482 8192000000 10095028640 10580467712 1129326528 12.115 100 35 69 1 1216119392 80100784 6692080
13:27:27 114474 8192000000 10050155968 10493485056 1124899600 12.073 99 22 44 1 1152819824 22931360 4988864
13:30:42 70065 8192000000 10076899792 10531901440 1137103664 12.188 100 15 30 1 1174200576 33593408 3503504
13:32:57 69720 8192000000 10113254448 10600034304 1143236400 12.246 100 7 14 0 1205131120 60221056 1673664
13:35:11 53374 8192000000 10085388784 10578468864 1129494400 12.117 99 15 29 1 1216630064 84067488 3068176
13:37:05 25087 8192000000 8950333488 9208918016 106946352 1.288 10 2 4 0 117205904 10188432 71120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-06-2022 13:38:56 to 22-06-2022 13:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 216 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2022 13:39:13 to 22-06-2022 13:39:15.

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          216768
MON$OLDEST_ACTIVE               216769
MON$OLDEST_SNAPSHOT             216769
MON$NEXT_TRANSACTION            216769
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                       2051296
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 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 ---
--- end of diff output ---

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

22.06.22 13:39:16. 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-06-22 13:39:18 No severe PSQL-related problems occured
22.06.22 13:39:20. End of report.