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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16112636 kB
MemAvailable 31250744 kB
Buffers 0 kB
Cached 15539644 kB
SwapCached 2328 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-14 07:37:09
DTS_END                         2022-01-14 07:37:09.4390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59254
TRN_ID                          219565
ATT_ID                          634
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: 8859.54 29432 1063145
customer order: creation 168.60 487 20232
customer order: refuse 14.61 435 1753
order to supplier: creation 44.88 1365 5385
order to supplier: removal 3.37 165552 404
invoice (draft): creation 44.64 3189 5357
invoice (draft): removal 51.61 988 6193
invoice accept: apply 40.05 25276 4806
invoice accept: cancel 45.97 1125 5516
customer reserve: creation 3660.14 243 439217
customer reserve: removal 4501.04 96 540125
realization accept: apply 82.05 284 9846
realization accept: cancel 76.77 158 9212
payment from customer: creation 58.21 183 6985
payment from customer: removal 5.47 114 656
payment to supplier: creation 46.88 200 5626
payment to supplier: removal 3.11 137 373
service: total inventory turnovers 3.78 4034 453
service: total monetary turnovers 7.42 374 890
service: aggregate perf. data 0.93 15292 112
service: refresh index statistics 0.03 369100 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2022 07:38:19 to 14-01-2022 07:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8965
TEST_TIME 2 7885
TEST_TIME 3 7375
TEST_TIME 4 6635
TEST_TIME 5 6782
TEST_TIME 6 7108
TEST_TIME 7 7431
TEST_TIME 8 7428
TEST_TIME 9 7748
TEST_TIME 10 7520
TEST_TIME 11 7586
TEST_TIME 12 7477
TEST_TIME 13 7449
TEST_TIME 14 7462
TEST_TIME 15 7518
TEST_TIME 16 7666
TEST_TIME 17 7728
TEST_TIME 18 7804
TEST_TIME 19 7848
TEST_TIME 20 7837
TEST_TIME 21 7929
TEST_TIME 22 8216
TEST_TIME 23 8550
TEST_TIME 24 8778
TEST_TIME 25 9036
TEST_TIME 26 9379
TEST_TIME 27 9586
TEST_TIME 28 9620
TEST_TIME 29 10041
TEST_TIME 30 10163
TEST_TIME 31 10922
TEST_TIME 32 11094
TEST_TIME 33 11020
TEST_TIME 34 10837
TEST_TIME 35 10578
TEST_TIME 36 10424
TEST_TIME 37 10484
TEST_TIME 38 10373
TEST_TIME 39 10343
TEST_TIME 40 10537
TEST_TIME 41 10540
TEST_TIME 42 10363
TEST_TIME 43 10461
TEST_TIME 44 10301
TEST_TIME 45 10199
TEST_TIME 46 10249
TEST_TIME 47 10124
TEST_TIME 48 10138
TEST_TIME 49 10198
TEST_TIME 50 10155
TEST_TIME 51 10193
TEST_TIME 52 10103
TEST_TIME 53 9922
TEST_TIME 54 9848
TEST_TIME 55 9774
TEST_TIME 56 9637
TEST_TIME 57 9562
TEST_TIME 58 9558
TEST_TIME 59 9490
TEST_TIME 60 9610
TEST_TIME 61 9496
TEST_TIME 62 9456
TEST_TIME 63 9461
TEST_TIME 64 9388
TEST_TIME 65 9375
TEST_TIME 66 9372
TEST_TIME 67 9384
TEST_TIME 68 9354
TEST_TIME 69 9381
TEST_TIME 70 9470
TEST_TIME 71 9453
TEST_TIME 72 9409
TEST_TIME 73 9395
TEST_TIME 74 9520
TEST_TIME 75 9535
TEST_TIME 76 9571
TEST_TIME 77 9590
TEST_TIME 78 9535
TEST_TIME 79 9474
TEST_TIME 80 9491
TEST_TIME 81 9392
TEST_TIME 82 9320
TEST_TIME 83 9301
TEST_TIME 84 9240
TEST_TIME 85 9231
TEST_TIME 86 9197
TEST_TIME 87 9129
TEST_TIME 88 9124
TEST_TIME 89 9093
TEST_TIME 90 9116
TEST_TIME 91 9093
TEST_TIME 92 9082
TEST_TIME 93 9071
TEST_TIME 94 9083
TEST_TIME 95 9126
TEST_TIME 96 9161
TEST_TIME 97 9211
TEST_TIME 98 9228
TEST_TIME 99 9214
TEST_TIME 100 9189
TEST_TIME 101 9192
TEST_TIME 102 9206
TEST_TIME 103 9276
TEST_TIME 104 9190
TEST_TIME 105 9155
TEST_TIME 106 9127
TEST_TIME 107 9095
TEST_TIME 108 9049
TEST_TIME 109 9018
TEST_TIME 110 9001
TEST_TIME 111 9021
TEST_TIME 112 9002
TEST_TIME 113 9014
TEST_TIME 114 8990
TEST_TIME 115 8985
TEST_TIME 116 8995
TEST_TIME 117 8984
TEST_TIME 118 8989
TEST_TIME 119 8952
TEST_TIME 120 8881

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-01-2022 07:38:22 to 14-01-2022 07:38:26.

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 1469958 1469958 0 0.00 0 35163 13 0 0
doc_list_biud 1469945 1469945 0 0.00 0 28255 41 0 0
sp_add_invoice_to_stock 4977 4806 171 3.43 62 213402 25277 0 171
sp_cancel_adding_invoice 5516 5516 0 0.00 5 87047 1125 0 0
sp_cancel_client_order 1753 1753 0 0.00 6 15439 435 0 0
sp_cancel_customer_reserve 540125 540125 0 0.00 1 28256 97 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 4459 114 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 15578 138 0 0
sp_cancel_supplier_invoice 6200 6193 7 0.11 2 17788 988 0 7
sp_cancel_supplier_order 404 404 0 0.00 8 1254675 165553 0 0
sp_cancel_write_off 9212 9212 0 0.00 2 12715 158 0 0
sp_client_order 20232 20232 0 0.00 6 36545 487 0 0
sp_customer_reserve 439217 439217 0 0.00 1 110441 244 0 0
sp_fill_shopping_cart 30963 30963 0 0.00 1 19443 83 0 0
sp_fill_shopping_cart_clo_res 439188 439188 0 0.00 0 18552 20 0 0
sp_get_clo_for_invoice 4769 4769 0 0.00 0 30186 291 0 0
sp_kill_cost_storno 15757 15757 0 0.00 0 8000 70 0 0
sp_kill_qstorno_ret_qs2qd 1096059 1096059 0 0.00 0 28252 42 0 0
sp_kill_qty_storno 577812 577812 0 0.00 0 28255 97 0 0
sp_lock_selected_doc 20290 20290 0 0.00 0 7948 7 0 0
sp_make_cost_storno 27222 27222 0 0.00 0 35144 80 0 0
sp_multiply_rows_for_pdistr 27222 27222 0 0.00 0 7859 36 0 0
sp_multiply_rows_for_qdistr 20232 20232 0 0.00 0 13699 177 0 0
sp_pay_from_customer 6985 6985 0 0.00 5 31355 184 0 0
sp_pay_to_supplier 5626 5626 0 0.00 6 15072 201 0 0
sp_payment_common 12611 12611 0 0.00 2 14788 46 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 15429 368 0 0
sp_qd_handle_on_invoice_upd_sts 10282 10282 0 0.00 0 26477 492 0 0
sp_qd_handle_on_reserve_upd_sts 9843 9843 0 0.00 0 18486 71 0 0
sp_reserve_write_off 9846 9846 0 0.00 8 18595 285 0 0
sp_supplier_invoice 5357 5357 0 0.00 10 90756 3190 0 0
sp_supplier_order 5385 5385 0 0.00 7 38814 1365 0 0
srv_aggregate_perf_data 112 112 0 0.00 2412 242416 15293 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 854 173111 37331 0 0
srv_find_qd_qs_mism 1017077 1017077 0 0.00 0 34378 10 0 0
srv_make_invnt_saldo 453 453 0 0.00 63 75689 4035 0 0
srv_make_money_saldo 890 890 0 0.00 1 18504 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 61200 570259 369100 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4811 4811 0 0.00 1 9261 925 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 6972 404 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 6296 31 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 578 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 797 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 752 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1736 41 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 92357 6935 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 2336 22 0 0
t$perf-norm:sp_client_order 20232 20232 0 0.00 0 6876 36 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 1120 17 0 0
t$perf-norm:sp_pay_from_customer 6985 6985 0 0.00 0 4476 18 0 0
t$perf-norm:sp_pay_to_supplier 5627 5627 0 0.00 0 6123 21 0 0
t$perf-norm:sp_reserve_write_off 9846 9846 0 0.00 0 3778 22 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 5561 35 0 0
t$perf-norm:sp_supplier_order 5385 5385 0 0.00 0 5872 35 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 562 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 50 4 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 823 11 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 419 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 177 57 0 0
v_add_invoice_to_stock 9870 9528 342 3.46 0 3020 23 0 342
v_all_customers 37418 37418 0 0.00 0 158 4 0 0
v_all_suppliers 29864 29864 0 0.00 0 81 4 0 0
v_all_wares 40460 40460 0 0.00 0 51 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 2095 18 0 0
v_cancel_client_order 3506 3506 0 0.00 0 2955 10 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 4390 13 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 1 1224 10 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 1901 13 0 0
v_cancel_supplier_invoice 828 814 14 1.69 0 1796 26 0 14
v_cancel_supplier_order 808 808 0 0.00 0 5788 25 0 0
v_cancel_write_off 2018 2018 0 0.00 0 1786 21 0 0
v_max_id_clo_ord 5381 5381 0 0.00 0 992 5 0 0
v_max_id_clo_res 968 968 0 0.00 0 2129 11 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 6902 9 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 59 7 0 0
v_max_non_paid_realizn 3452 3452 0 0.00 0 6824 9 0 0
v_min_id_clo_ord 5381 5381 0 0.00 0 2211 7 0 0
v_min_id_clo_res 968 968 0 0.00 0 3006 12 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 3896 7 0 0
v_min_non_paid_invoice 5626 5626 0 0.00 0 7598 15 0 0
v_min_non_paid_realizn 6985 6985 0 0.00 0 6322 17 0 0
v_our_firm 10108 10108 0 0.00 0 53 5 0 0
v_reserve_write_off 19686 19686 0 0.00 0 3036 11 0 0
x_lock_depdocs_on_canc_invoice 11302 11302 0 0.00 0 30392 124 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 3731 320 0 0
x_make_qstorno_1000_1200 5385 5385 0 0.00 2 38783 1199 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 5 82300 2959 0 0
x_make_qstorno_2100_3300 439216 439216 0 0.00 0 110436 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2022 07:38:27 to 14-01-2022 07:38:31.

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
05:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:14 17947 8192000000 9711400416 10006433792 791896544 8.814 100 85 170 0 819838512 7215104 20726864
05:09:38 23542 8192000000 9760155360 10063519744 831735872 9.217 100 90 180 0 864837664 11748800 21352992
05:11:06 11839 8192000000 9790487360 10104225792 861729008 9.517 100 89 178 0 899698992 17249792 20720192
05:12:27 12775 8192000000 9851682016 10179977216 903782512 9.936 100 77 154 0 954146000 33388832 16974656
05:13:44 2917 8192000000 9919389936 10260168704 956966480 10.459 100 91 183 0 1035296112 59233648 19095984
05:14:52 2414 8192000000 9959600464 10312593408 993199152 10.813 100 88 173 0 1081619984 71276608 17144224
05:15:57 2705 8192000000 9987630000 10350264320 1021434544 11.086 100 83 163 1 1100587136 63091168 16061424
05:17:05 1108 8192000000 10030661840 10397302784 1045956352 11.322 100 98 190 1 1149467584 84152160 19359072
05:18:07 1468 8192000000 10032062400 10403323904 1059381360 11.451 100 97 193 1 1149688528 70441600 19865568
05:19:11 1358 8192000000 10035371760 10410713088 1069505024 11.547 100 95 191 1 1155086016 65920544 19660448
05:20:15 1018 8192000000 10055703808 10436534272 1074897328 11.599 100 87 167 3 1173675024 81557616 17220080
05:21:17 1366 8192000000 10053931472 10434916352 1083718512 11.683 100 98 188 4 1175171104 71781552 19671040
05:22:19 15152 8192000000 10022601728 10429583360 1082601808 11.672 100 52 102 1 1161929744 69417712 9910224
05:23:35 1403 8192000000 10037372624 10428407808 1090248768 11.745 100 93 174 2 1164298192 56465696 17583728
05:24:37 986 8192000000 10049646800 10437521408 1096098080 11.801 100 84 159 0 1173212400 61114656 15999664
05:25:39 2687 8192000000 10068560224 10462203904 1094498848 11.785 100 73 144 0 1184143968 75460656 14184464
05:26:42 1650 8192000000 10062879168 10452643840 1104174336 11.877 100 84 161 2 1178450896 56221440 18055120
05:27:45 1245 8192000000 10074862512 10466807808 1107364352 11.907 100 94 176 3 1194036400 69374480 17297568
05:28:47 1232 8192000000 10051075872 10432536576 1077367616 11.622 100 96 184 2 1177144960 81047152 18730192
05:29:49 1399 8192000000 10039620880 10421420032 1087128160 11.715 100 98 189 0 1160841824 54469456 19244208
05:30:51 1021 8192000000 10049291072 10429210624 1077890736 11.627 100 98 189 0 1167252000 69147904 20213360
05:31:52 1347 8192000000 10069965168 10452873216 1077937184 11.628 100 96 188 3 1188857088 92057360 18862544
05:32:55 1313 8192000000 10031703664 10408398848 1060924544 11.465 100 98 195 1 1156691360 74373376 21393440
05:33:58 949 8192000000 9973989104 10337202176 1026159920 11.131 100 96 187 2 1096924432 50993120 19771392
05:34:59 3998 8192000000 10001864736 10378063872 1042345056 11.287 100 76 148 2 1125013520 67784096 14884368
05:36:04 7951 8192000000 9980276800 10350088192 1022429392 11.095 98 77 151 0 1098515840 60629264 15457184
05:37:15 1630 8192000000 9997825168 10373214208 1059809408 11.455 100 90 162 4 1116874432 43310896 13754128
05:38:25 1362 8192000000 10058926096 10439438336 1082515168 11.671 100 81 155 0 1176897984 76728352 17654464
05:39:30 995 8192000000 10051602512 10431598592 1086034160 11.705 100 92 165 0 1165107376 62369728 16703488
05:40:37 3007 8192000000 10072072528 10458705920 1095857312 11.798 100 72 141 1 1195454048 84862640 14734096
05:41:47 3826 8192000000 10088315824 10478923776 1108172672 11.915 100 61 119 0 1202365296 81473344 12719280
05:42:53 1488 8192000000 10135001568 10523574272 1117369968 12.002 100 95 190 1 1258751504 120080272 21301264
05:43:58 970 8192000000 10176982752 10577641472 1128549856 12.108 100 94 182 1 1293006368 143936368 20520144
05:45:04 4490 8192000000 10159629888 10556129280 1133965120 12.159 100 81 158 0 1280363136 129419888 16978128
05:46:11 1359 8192000000 10200213248 10612297728 1150951664 12.318 100 98 192 1 1313245520 141681600 20612256
05:47:16 1128 8192000000 10239753952 10638196736 1156477872 12.370 100 88 173 2 1351722512 174891408 20353232
05:48:22 939 8192000000 10263427952 10660605952 1171852864 12.514 100 91 166 0 1372010384 183315280 16842240
05:49:26 2047 8192000000 10308948384 10712702976 1178824640 12.579 100 82 158 1 1417728480 220851424 18052416
05:50:30 1127 8192000000 10368606752 10767917056 1184013376 12.628 100 96 188 0 1476204112 269669536 22521200
05:51:37 1251 8192000000 10383170848 10806927360 1184841776 12.635 100 89 177 0 1507245264 301726688 20676800
05:52:42 1658 8192000000 10401354672 10838798336 1187974384 12.665 100 91 176 1 1515439168 307762704 19702080
05:53:48 2484 8192000000 10412755408 10830168064 1181753808 12.607 100 89 170 0 1522007104 320458432 19794864
05:54:54 1697 8192000000 10441197712 10859970560 1194595872 12.726 100 94 188 0 1550773728 333432176 22745680
05:56:00 1227 8192000000 10487398816 10896351232 1192615552 12.708 100 94 185 0 1592086608 377660768 21810288
05:57:06 1621 8192000000 10491605584 10905554944 1182551904 12.614 100 91 176 0 1595708768 391434800 21722064
05:58:09 927 8192000000 10508976256 10933448704 1172583328 12.521 99 98 192 1 1616187936 420236208 23368400
05:59:12 849 8192000000 10559728480 10991591424 1191293936 12.695 100 94 186 0 1664550480 449909744 23346800
06:00:14 4215 8192000000 10474301680 10941837312 1163441984 12.435 100 54 108 0 1583256544 405453136 14361424
06:01:23 5439 8192000000 10480010736 10952241152 1163173968 12.433 100 59 116 0 1595963136 417247008 15542160
06:02:30 6530 8192000000 10484101008 10950946816 1164928416 12.449 100 54 106 0 1579367312 399722496 14716400
06:03:38 2760 8192000000 10454140336 10930061312 1170973792 12.506 100 51 101 0 1557317792 374333488 12010512
06:05:19 40598 8192000000 10429902208 10887208960 1151859328 12.327 99 26 52 0 1525934816 367599680 6475808
06:07:00 20448 8192000000 10332357040 10776715264 1124564128 12.070 99 23 46 1 1432138128 301532640 6041360
06:08:28 21523 8192000000 10140319856 10652798976 1123827936 12.063 99 15 30 1 1273523344 146335360 3360048
06:09:50 53611 8192000000 10149864224 10569326592 1113075984 11.962 97 34 62 3 1220932944 100581584 7275376
06:11:45 59572 8192000000 10196215056 10634457088 1131964048 12.140 99 35 67 1 1285973488 146079216 7930224
06:14:32 61866 8192000000 10224633792 10738286592 1130490912 12.126 99 16 32 0 1323227312 189175408 3560992
06:16:34 37648 8192000000 10144002368 10701488128 1134315312 12.162 100 17 34 1 1289764880 150819168 4630400
06:18:22 80395 8192000000 10126897760 10570440704 1142796192 12.242 100 26 47 0 1203139952 55684832 4658928
06:20:43 73857 8192000000 10093745424 10523791360 1125004720 12.074 98 48 81 3 1171496544 36529808 9962016
06:23:30 97875 8192000000 10084515424 10538119168 1112145152 11.953 98 25 48 2 1168359456 50782224 5432080
06:26:12 173110 8192000000 10040050528 10473431040 1044323888 11.306 92 19 38 1 1086550144 38875536 3350720
06:30:06 104841 8192000000 10050633648 10483339264 1113057552 11.961 99 32 60 2 1141489408 22077232 6354624
06:32:51 88136 8192000000 10088066272 10519990272 1129747344 12.119 100 34 66 0 1189159648 51891808 7520496
06:36:09 66512 8192000000 10177744832 10643107840 1139610544 12.212 100 51 102 0 1265712320 114696272 11405504
06:38:28 126293 8192000000 10088706640 10532655104 1092674080 11.768 96 14 28 0 1140305312 44703520 2927712
06:41:35 49748 8192000000 10070224416 10542637056 1129351984 12.115 99 6 12 0 1170238000 39724400 1161616
06:43:25 74413 8192000000 10119897776 10571202560 1124324256 12.068 98 19 38 4 1200275040 71833248 4117536
06:45:40 52018 8192000000 10122080928 10583437312 1131949280 12.140 98 14 28 1 1197955696 63231056 2775360
06:47:34 48647 8192000000 10110277632 10579181568 1142349120 12.238 100 16 32 0 1180071088 33821392 3900576
06:49:24 27152 8192000000 10133690816 10567483392 1127406816 12.097 99 47 88 1 1215054960 77805296 9842848
06:50:51 65873 8192000000 10120859520 10609090560 1118966128 12.017 98 30 60 0 1234581728 109135216 6480384
06:53:08 23380 8192000000 10111219040 10566590464 1138184368 12.198 100 50 98 1 1218151824 69360592 10606864
06:54:39 51088 8192000000 10085396256 10510729216 1097536048 11.814 96 30 60 2 1151416816 47601680 6279088
06:56:32 130489 8192000000 10031837888 10469371904 1101982912 11.856 98 27 54 1 1175342096 67801776 5557408
07:00:07 61257 8192000000 10057922720 10507644928 1088607456 11.729 97 24 47 0 1157996368 64767568 4621344
07:02:41 38115 8192000000 10055648256 10499330048 1098384576 11.822 98 16 32 0 1146276560 44547776 3344208
07:04:59 51717 8192000000 10063003792 10529337344 1114495824 11.975 100 32 61 1 1183186000 59456560 9233616
07:08:10 33942 8192000000 10099418336 10548858880 1095026752 11.790 97 30 58 4 1168695840 67699024 5970064
07:10:09 42555 8192000000 10137412752 10575609856 1141366768 12.228 100 31 62 0 1223747424 75858800 6521856
07:11:57 28310 8192000000 10161522480 10616320000 1140086576 12.216 100 39 78 0 1264040096 115588752 8364768
07:13:38 73607 8192000000 10082734960 10538475520 1126143104 12.085 99 16 30 2 1197714608 68233984 3337520
07:15:55 33642 8192000000 10072063056 10501586944 1117113664 12.000 99 47 94 0 1174627024 47410672 10102688
07:17:30 10651 8192000000 10096275824 10516189184 1088283184 11.726 96 60 117 1 1165063200 65320736 11459280
07:18:44 16703 8192000000 10115863536 10535677952 1122138816 12.047 99 48 96 1 1205688704 73694864 9855024
07:20:02 69457 8192000000 10083471552 10561290240 1108410080 11.917 98 12 24 0 1219292880 108100912 2781888
07:23:41 63181 8192000000 10079411616 10528120832 1114759296 11.977 98 26 52 1 1173441360 53297440 5384624
07:27:06 76076 8192000000 10085897616 10602987520 1132603552 12.146 99 12 22 2 1233550432 98142672 2804208
07:29:23 82161 8192000000 10085821136 10585718784 1132571264 12.146 99 13 26 1 1197598128 62273600 2753264
07:31:46 61089 8192000000 10101336800 10547208192 1121004688 12.036 98 46 85 1 1208960592 78386816 9569088
07:34:06 15682 8192000000 10089195584 10527477760 1123237280 12.058 99 48 96 1 1171966544 38989392 9739872
07:35:25 36529 8192000000 10124388144 10555949056 1121396176 12.040 99 35 70 1 1195689424 67133072 7160176
07:37:02 22055 8192000000 9212588272 9545854976 334258160 3.920 30 5 6 2 366652656 31193904 1200592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-01-2022 07:38:33 to 14-01-2022 07:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 171 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 14-01-2022 07:38:50 to 14-01-2022 07:38:51.

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          219832
MON$OLDEST_ACTIVE               219833
MON$OLDEST_SNAPSHOT             219833
MON$NEXT_TRANSACTION            219833
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                       2070880
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             772
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 ---
--- end of diff output ---

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

14.01.22 07:38:53. 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-14 07:38:55 No severe PSQL-related problems occured
14.01.22 07:38:56. End of report.