Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2022 04: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 16324800 kB
MemAvailable 31576524 kB
Buffers 20 kB
Cached 15335852 kB
SwapCached 2204 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-04-01 04:37:14
DTS_END                         2022-04-01 04:37:14.7310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43930
TRN_ID                          220396
ATT_ID                          639
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.28 30335 1060954
customer order: creation 175.68 497 21081
customer order: refuse 15.39 459 1847
order to supplier: creation 46.14 1466 5537
order to supplier: removal 3.14 199493 377
invoice (draft): creation 47.17 3487 5660
invoice (draft): removal 46.22 1191 5546
invoice accept: apply 42.83 27850 5140
invoice accept: cancel 41.44 1588 4973
customer reserve: creation 4017.45 261 482094
customer reserve: removal 4108.96 120 493075
realization accept: apply 86.08 286 10330
realization accept: cancel 77.15 183 9258
payment from customer: creation 60.53 180 7264
payment from customer: removal 5.76 140 691
payment to supplier: creation 50.98 197 6117
payment to supplier: removal 3.61 134 433
service: total inventory turnovers 4.10 4587 492
service: total monetary turnovers 7.77 429 932
service: aggregate perf. data 0.86 17996 103
service: refresh index statistics 0.03 346159 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-04-2022 04:38:39 to 01-04-2022 04:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8819
TEST_TIME 2 7837
TEST_TIME 3 6169
TEST_TIME 4 6380
TEST_TIME 5 6043
TEST_TIME 6 5953
TEST_TIME 7 5811
TEST_TIME 8 5559
TEST_TIME 9 5765
TEST_TIME 10 6023
TEST_TIME 11 6190
TEST_TIME 12 6284
TEST_TIME 13 6227
TEST_TIME 14 6265
TEST_TIME 15 6584
TEST_TIME 16 6687
TEST_TIME 17 6860
TEST_TIME 18 6983
TEST_TIME 19 7154
TEST_TIME 20 7230
TEST_TIME 21 7224
TEST_TIME 22 7369
TEST_TIME 23 7449
TEST_TIME 24 7497
TEST_TIME 25 7508
TEST_TIME 26 7532
TEST_TIME 27 7696
TEST_TIME 28 7592
TEST_TIME 29 7765
TEST_TIME 30 8234
TEST_TIME 31 8654
TEST_TIME 32 9369
TEST_TIME 33 9442
TEST_TIME 34 9712
TEST_TIME 35 9986
TEST_TIME 36 10490
TEST_TIME 37 10705
TEST_TIME 38 10683
TEST_TIME 39 10553
TEST_TIME 40 10437
TEST_TIME 41 10297
TEST_TIME 42 10188
TEST_TIME 43 10008
TEST_TIME 44 9866
TEST_TIME 45 9826
TEST_TIME 46 9764
TEST_TIME 47 9715
TEST_TIME 48 9633
TEST_TIME 49 9591
TEST_TIME 50 9678
TEST_TIME 51 9663
TEST_TIME 52 9555
TEST_TIME 53 9490
TEST_TIME 54 9469
TEST_TIME 55 9449
TEST_TIME 56 9639
TEST_TIME 57 9599
TEST_TIME 58 9639
TEST_TIME 59 9554
TEST_TIME 60 9472
TEST_TIME 61 9527
TEST_TIME 62 9741
TEST_TIME 63 9662
TEST_TIME 64 9655
TEST_TIME 65 9619
TEST_TIME 66 9694
TEST_TIME 67 9665
TEST_TIME 68 9537
TEST_TIME 69 9538
TEST_TIME 70 9456
TEST_TIME 71 9416
TEST_TIME 72 9424
TEST_TIME 73 9401
TEST_TIME 74 9374
TEST_TIME 75 9324
TEST_TIME 76 9310
TEST_TIME 77 9277
TEST_TIME 78 9216
TEST_TIME 79 9247
TEST_TIME 80 9301
TEST_TIME 81 9280
TEST_TIME 82 9252
TEST_TIME 83 9293
TEST_TIME 84 9303
TEST_TIME 85 9307
TEST_TIME 86 9422
TEST_TIME 87 9347
TEST_TIME 88 9264
TEST_TIME 89 9253
TEST_TIME 90 9201
TEST_TIME 91 9149
TEST_TIME 92 9103
TEST_TIME 93 9051
TEST_TIME 94 9012
TEST_TIME 95 8987
TEST_TIME 96 8997
TEST_TIME 97 8995
TEST_TIME 98 8975
TEST_TIME 99 9001
TEST_TIME 100 9013
TEST_TIME 101 8988
TEST_TIME 102 9080
TEST_TIME 103 9088
TEST_TIME 104 9208
TEST_TIME 105 9167
TEST_TIME 106 9187
TEST_TIME 107 9163
TEST_TIME 108 9113
TEST_TIME 109 9072
TEST_TIME 110 9057
TEST_TIME 111 9053
TEST_TIME 112 9006
TEST_TIME 113 8964
TEST_TIME 114 8946
TEST_TIME 115 8954
TEST_TIME 116 8921
TEST_TIME 117 8906
TEST_TIME 118 8888
TEST_TIME 119 8900
TEST_TIME 120 8871

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-04-2022 04:38:43 to 01-04-2022 04:38:46.

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 1502589 1502589 0 0.00 0 29925 15 0 0
doc_list_biud 1502578 1502578 0 0.00 0 78040 46 0 0
sp_add_invoice_to_stock 5278 5140 138 2.61 38 294621 27850 0 138
sp_cancel_adding_invoice 4973 4973 0 0.00 8 75009 1588 0 0
sp_cancel_client_order 1847 1847 0 0.00 6 13554 460 0 0
sp_cancel_customer_reserve 493075 493075 0 0.00 1 78041 121 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 6429 141 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 4 6997 135 0 0
sp_cancel_supplier_invoice 5560 5546 14 0.25 4 14668 1192 0 14
sp_cancel_supplier_order 377 377 0 0.00 7 1556409 199493 0 0
sp_cancel_write_off 9258 9258 0 0.00 2 7251 184 0 0
sp_client_order 21081 21081 0 0.00 6 17369 498 0 0
sp_customer_reserve 482094 482094 0 0.00 1 155434 262 0 0
sp_fill_shopping_cart 32268 32268 0 0.00 2 39108 89 0 0
sp_fill_shopping_cart_clo_res 482060 482060 0 0.00 0 43856 20 0 0
sp_get_clo_for_invoice 5092 5092 0 0.00 0 64410 298 0 0
sp_kill_cost_storno 15355 15355 0 0.00 0 7298 83 0 0
sp_kill_qstorno_ret_qs2qd 1001331 1001331 0 0.00 0 78037 52 0 0
sp_kill_qty_storno 530495 530495 0 0.00 0 78040 120 0 0
sp_lock_selected_doc 21382 21382 0 0.00 0 7921 10 0 0
sp_make_cost_storno 28802 28802 0 0.00 0 29913 83 0 0
sp_multiply_rows_for_pdistr 28800 28800 0 0.00 0 16350 37 0 0
sp_multiply_rows_for_qdistr 21080 21080 0 0.00 0 14097 207 0 0
sp_pay_from_customer 7264 7264 0 0.00 6 12611 180 0 0
sp_pay_to_supplier 6117 6117 0 0.00 7 9246 198 0 0
sp_payment_common 13381 13381 0 0.00 2 7008 48 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 1 13547 384 0 0
sp_qd_handle_on_invoice_upd_sts 10062 10062 0 0.00 0 55949 593 0 0
sp_qd_handle_on_reserve_upd_sts 10330 10330 0 0.00 0 12022 58 0 0
sp_reserve_write_off 10330 10330 0 0.00 8 19117 286 0 0
sp_supplier_invoice 5660 5660 0 0.00 10 133781 3488 0 0
sp_supplier_order 5537 5537 0 0.00 7 30809 1467 0 0
srv_aggregate_perf_data 103 103 0 0.00 2377 143846 17996 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 852 166833 31849 0 0
srv_find_qd_qs_mism 1009297 1009297 0 0.00 0 26066 13 0 0
srv_make_invnt_saldo 492 492 0 0.00 37 118146 4587 0 0
srv_make_money_saldo 932 932 0 0.00 1 10135 429 0 0
srv_recalc_idx_stat 4 4 0 0.00 250325 422819 346160 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5146 5146 0 0.00 0 9324 1057 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 2 5928 524 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 2892 32 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1658 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 808 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 642 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1370 32 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 103199 9051 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 1986 21 0 0
t$perf-norm:sp_client_order 21082 21082 0 0.00 0 6170 41 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 1190 17 0 0
t$perf-norm:sp_pay_from_customer 7264 7264 0 0.00 0 3686 22 0 0
t$perf-norm:sp_pay_to_supplier 6117 6117 0 0.00 0 13220 23 0 0
t$perf-norm:sp_reserve_write_off 10330 10330 0 0.00 0 8723 24 0 0
t$perf-norm:sp_supplier_invoice 5661 5661 0 0.00 0 7934 35 0 0
t$perf-norm:sp_supplier_order 5537 5537 0 0.00 0 4095 36 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 164 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1035 18 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 4193 26 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 16788 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 337 103 0 0
v_add_invoice_to_stock 10454 10178 276 2.64 0 7591 24 0 276
v_all_customers 38965 38965 0 0.00 0 98 4 0 0
v_all_suppliers 31646 31646 0 0.00 0 68 5 0 0
v_all_wares 42158 42158 0 0.00 0 56 4 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 712 15 0 0
v_cancel_client_order 3694 3694 0 0.00 0 798 9 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 367 9 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2329 12 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 583 13 0 0
v_cancel_supplier_invoice 844 816 28 3.31 0 1044 30 0 28
v_cancel_supplier_order 754 754 0 0.00 0 2730 15 0 0
v_cancel_write_off 2108 2108 0 0.00 0 1097 24 0 0
v_max_id_clo_ord 5536 5536 0 0.00 0 580 5 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 1599 15 0 0
v_max_id_ord_sup 5653 5653 0 0.00 0 6211 9 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 80 8 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 647 7 0 0
v_min_id_clo_ord 5536 5536 0 0.00 0 5759 10 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 6380 22 0 0
v_min_id_ord_sup 5653 5653 0 0.00 0 6599 12 0 0
v_min_non_paid_invoice 6117 6117 0 0.00 0 6293 18 0 0
v_min_non_paid_realizn 7264 7264 0 0.00 0 6426 18 0 0
v_our_firm 10212 10212 0 0.00 0 146 5 0 0
v_reserve_write_off 20660 20660 0 0.00 0 2940 9 0 0
x_lock_depdocs_on_canc_invoice 10111 10111 0 0.00 0 21038 146 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 4319 400 0 0
x_make_qstorno_1000_1200 5537 5537 0 0.00 3 30796 1278 0 0
x_make_qstorno_1200_2000 5660 5660 0 0.00 5 133305 3244 0 0
x_make_qstorno_2100_3300 482092 482092 0 0.00 0 155431 228 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-04-2022 04:38:47 to 01-04-2022 04:38:52.

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
02:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:19 32305 8192000000 9707399680 10002640896 783201184 8.726 100 89 178 0 813262448 7740944 22320320
02:09:58 13982 8192000000 9753444464 10057535488 820820384 9.107 100 85 170 1 853157920 11294768 21042768
02:11:18 18276 8192000000 9786395168 10104381440 856870176 9.469 100 82 164 0 895101216 19026960 19204080
02:12:42 15364 8192000000 9867832016 10197762048 911698336 10.014 100 85 170 0 971339696 40883056 18758304
02:14:01 7716 8192000000 9906734336 10248572928 948454672 10.376 100 79 157 0 1022571616 57668224 16448720
02:15:11 2952 8192000000 9958407312 10308734976 990756704 10.789 100 86 164 2 1069051440 60984928 17309808
02:16:19 2923 8192000000 9992674000 10357747712 1021145200 11.083 100 86 170 1 1107135840 68574960 17415680
02:17:24 2116 8192000000 10017366800 10387558400 1045080032 11.313 100 89 171 1 1145340560 83444432 16816096
02:18:27 1783 8192000000 10038029504 10415091712 1052893456 11.388 100 88 172 0 1161841120 92148144 16799520
02:19:32 1004 8192000000 10021582960 10392383488 1069161392 11.544 100 87 164 2 1140422560 55669328 15591840
02:20:35 1122 8192000000 10033008304 10413146112 1077193728 11.621 100 93 172 3 1160498624 64763344 18541552
02:21:37 12223 8192000000 10024890784 10407964672 1077785424 11.626 100 87 161 4 1142175136 50201088 14188624
02:22:50 1211 8192000000 10066020928 10450067456 1083149200 11.677 100 98 188 1 1189444592 87041424 19253968
02:23:53 962 8192000000 10061263488 10456178688 1090875264 11.751 100 85 164 0 1184719232 76842544 17001424
02:24:56 1250 8192000000 10074929248 10461167616 1094193376 11.783 100 99 191 1 1195257552 81699888 19364288
02:25:58 1506 8192000000 10061032736 10448572416 1098371072 11.822 100 99 192 5 1185793280 67580448 19841760
02:27:00 1325 8192000000 10073044912 10463997952 1108320176 11.917 100 92 173 0 1199723792 73987008 17416608
02:28:03 1167 8192000000 10070806992 10462101504 1102920224 11.865 100 83 163 1 1189123312 69803456 16399632
02:29:04 1030 8192000000 10059497824 10449149952 1106265712 11.897 100 90 176 2 1180480400 56275968 17938720
02:30:08 5165 8192000000 10035589840 10424696832 1073507440 11.586 98 69 138 3 1160975888 72926688 14541760
02:31:14 1122 8192000000 10070748528 10461147136 1099275568 11.831 100 93 178 3 1192783952 74483904 19024480
02:32:16 1741 8192000000 10026932128 10414727168 1075669840 11.606 100 77 150 2 1150074272 58563440 15840992
02:33:19 5668 8192000000 10015681968 10396336128 1072438432 11.575 100 76 145 1 1135309904 48739632 14131840
02:34:25 956 8192000000 9994061984 10365825024 1032304416 11.191 99 93 181 0 1116166256 63688288 20173552
02:35:27 1348 8192000000 9980866544 10353369088 1038428208 11.250 100 74 137 4 1104133232 51037424 14667600
02:36:29 6226 8192000000 9975280640 10351366144 1028299632 11.152 100 59 118 0 1098553312 58815152 11438528
02:37:47 2104 8192000000 9983797936 10361151488 1035978608 11.226 99 68 124 1 1102837360 54007104 12851648
02:38:58 1062 8192000000 10034116128 10414088192 1062490112 11.480 100 95 183 2 1161789056 79334416 19964528
02:40:03 1508 8192000000 10030611904 10407383040 1074142528 11.592 100 95 185 2 1146505920 52836464 19526928
02:41:36 5181 8192000000 10063703808 10461237248 1087634832 11.720 100 67 126 0 1188242832 88165904 12442096
02:42:48 3141 8192000000 10076543760 10469036032 1102155664 11.858 100 83 153 0 1193467024 75589792 15721568
02:43:58 3388 8192000000 10133368960 10520088576 1123200224 12.057 100 80 149 1 1248070688 109787760 15082704
02:45:08 3228 8192000000 10161722928 10555097088 1136234320 12.180 100 74 134 1 1276705968 125817824 14653824
02:46:18 925 8192000000 10210850288 10604285952 1144047840 12.254 100 90 172 0 1323956864 161227360 18681664
02:47:23 1766 8192000000 10231931600 10634743808 1150472256 12.314 100 88 171 1 1345695552 176846112 18377184
02:48:30 1136 8192000000 10289047776 10691194880 1168687936 12.485 100 94 183 0 1397173312 206575760 21909616
02:49:35 3517 8192000000 10292449664 10695278592 1178470160 12.576 100 81 158 0 1405083328 209450320 17162848
02:50:45 1263 8192000000 10329097472 10727997440 1182916688 12.617 100 95 186 2 1438037264 232768752 22351824
02:51:50 1899 8192000000 10341789904 10764435456 1193175280 12.713 100 96 190 2 1451793792 236501680 22116832
02:52:55 1860 8192000000 10371244288 10773884928 1193873616 12.719 100 97 186 3 1483354496 269095984 20384896
02:54:00 968 8192000000 10376847312 10788392960 1184483040 12.632 100 91 169 1 1484719184 281176192 19059952
02:55:04 989 8192000000 10405701040 10822905856 1197260160 12.751 100 95 187 3 1517876880 299449056 21167664
02:56:07 999 8192000000 10416977408 10832052224 1187363984 12.659 100 92 177 2 1525838416 318559136 19915296
02:57:11 1473 8192000000 10439568320 10847133696 1185999696 12.646 100 91 172 0 1549996384 342322176 21674512
02:58:15 1157 8192000000 10496347536 10900013056 1184246672 12.630 100 97 189 1 1601881056 395061792 22572592
02:59:19 1082 8192000000 10509376416 10943975424 1196536640 12.744 100 95 184 3 1610546320 391858688 22150992
03:00:25 1465 8192000000 10541498320 10966478848 1191248720 12.695 100 91 178 0 1646990272 435071952 20669600
03:01:29 996 8192000000 10584860224 10994868224 1192856112 12.710 100 96 186 0 1686856240 471542528 22457600
03:02:33 3969 8192000000 10605470192 11052486656 1193279952 12.714 100 88 175 0 1708500432 492735056 22485424
03:03:39 1066 8192000000 10651904192 11083087872 1190262784 12.686 100 96 184 0 1752309360 539339136 22707440
03:04:43 1833 8192000000 10649121728 11099971584 1193313072 12.714 100 76 148 1 1753536096 540934224 19288800
03:05:52 849 8192000000 10668715408 11133792256 1178300912 12.574 100 97 188 0 1769837600 568459872 23076816
03:06:57 4422 8192000000 10630437920 11133509632 1185473952 12.641 100 77 144 2 1733873712 529575152 18824608
03:08:07 5526 8192000000 10546040896 11061624832 1190220272 12.685 100 73 146 0 1674015968 465696464 18099232
03:09:15 1391 8192000000 10452564992 10992594944 1173613232 12.531 100 70 131 1 1558490816 367247088 17630496
03:10:21 18496 8192000000 10395755616 10873118720 1171743168 12.513 100 30 59 1 1517365872 337810800 7811904
03:11:41 53938 8192000000 10244906320 10724909056 1123741664 12.062 98 27 54 1 1376031632 244764000 7525968
03:13:45 22759 8192000000 10088081984 10595254272 1100680144 11.844 98 18 36 1 1205484736 100457936 4346656
03:15:53 32663 8192000000 10100978496 10539327488 1142394448 12.238 100 40 78 3 1199344368 47106288 9843632
03:17:27 72071 8192000000 10113385936 10548314112 1134660128 12.165 98 38 75 1 1188198544 45561696 7976720
03:19:40 127558 8192000000 10112673296 10572484608 1135280736 12.171 99 30 60 2 1220914624 78398384 7235504
03:22:51 15235 8192000000 10090279648 10538090496 1147472672 12.286 100 29 58 0 1196657072 42705488 6478912
03:24:08 28260 8192000000 10112041264 10555088896 1118295312 12.011 97 29 58 0 1199197184 74266704 6635168
03:25:48 37552 8192000000 10207453856 10642796544 1145786704 12.270 98 66 132 0 1277845648 117779776 14279168
03:27:38 166833 8192000000 10151638128 10603327488 1071416816 11.566 93 16 32 1 1191502064 116570000 3515248
03:31:25 98706 8192000000 10171821232 10630205440 1127699296 12.100 99 23 44 1 1272539856 139626656 5213904
03:34:05 47930 8192000000 10107528208 10573242368 1120499264 12.032 98 27 53 2 1186457056 59372720 6585072
03:35:54 31555 8192000000 10101470544 10533486592 1111191472 11.944 98 32 61 0 1177261712 59836464 6233776
03:37:46 48917 8192000000 10165870288 10647908352 1124563520 12.070 99 34 68 1 1249132512 117291872 7277120
03:39:35 50838 8192000000 10057242528 10492530688 1114479776 11.975 98 23 44 0 1146900528 28101904 4318848
03:41:37 57863 8192000000 10123770112 10580168704 1114426608 11.974 98 37 74 1 1187779392 66221568 7131216
03:43:46 54588 8192000000 10100927488 10566995968 1128950352 12.111 99 11 23 0 1198430880 67280000 2200528
03:46:34 53677 8192000000 10086649408 10529943552 1136652560 12.184 100 35 70 0 1177117952 34178864 6286528
03:48:31 55371 8192000000 10093275888 10563538944 1124152880 12.066 98 33 66 0 1195506992 64585344 6768768
03:50:32 52612 8192000000 10040662944 10531487744 1096065408 11.800 97 9 18 0 1145519264 47482864 1970992
03:52:29 23867 8192000000 10058392560 10503077888 1114423968 11.974 98 23 45 1 1144317616 24837536 5056112
03:53:53 36138 8192000000 10075070112 10517704704 1121027808 12.037 99 29 56 1 1174035344 46918752 6088784
03:55:39 11034 8192000000 10141190976 10564628480 1139788048 12.214 100 72 142 1 1244099344 88977264 15334032
03:56:51 57435 8192000000 10070572048 10530504704 1102507072 11.861 97 29 58 2 1158101088 49722736 5871280
03:58:49 34138 8192000000 10092542720 10521546752 1095601264 11.796 97 26 52 1 1180627136 78804176 6221696
04:00:26 47438 8192000000 10111589200 10540974080 1092220736 11.764 97 31 61 3 1204661680 106448256 5992688
04:02:14 57148 8192000000 10155861888 10595885056 1107133136 11.905 98 37 72 0 1200499392 85603696 7762560
04:04:17 62883 8192000000 10072395616 10504065024 1126361216 12.087 100 51 102 0 1181911040 45350752 10199072
04:06:46 46835 8192000000 10042794544 10508505088 1107613472 11.910 98 16 31 0 1149195168 38714704 2866992
04:08:47 53268 8192000000 10061022096 10534256640 1119774544 12.025 99 9 18 1 1162204464 40543376 1886544
04:10:41 34204 8192000000 10095600864 10544484352 1103604640 11.872 98 28 53 2 1179521424 69894624 6022160
04:12:16 37546 8192000000 10135558832 10584465408 1133134304 12.151 100 28 54 5 1239867328 100801648 5931376
04:13:54 36814 8192000000 10145240800 10589822976 1131446480 12.135 99 29 59 1 1230744464 93087136 6210848
04:15:39 44798 8192000000 10152462816 10615767040 1130763680 12.129 99 33 66 2 1265991440 127966880 7260880
04:17:24 62643 8192000000 10167935856 10607382528 1125839072 12.082 98 30 60 0 1240234080 108139872 6255136
04:19:29 66286 8192000000 10118978624 10572296192 1130759920 12.129 99 23 46 0 1234448240 99082352 4605968
04:21:38 71201 8192000000 10092476784 10535686144 1102670304 11.863 97 23 46 1 1135448048 27966624 4811120
04:23:49 101592 8192000000 10070564768 10508271616 1109859872 11.931 98 42 84 2 1178275504 59514496 8901136
04:26:45 6074 8192000000 10046167920 10485911552 1105774816 11.892 99 41 77 3 1152911776 38585952 8551008
04:27:51 87612 8192000000 10059299792 10547785728 1120721744 12.034 100 11 22 1 1155850928 32940368 2188816
04:30:19 52432 8192000000 10070724992 10523729920 1109801392 11.931 99 32 64 0 1165752432 48647968 7303072
04:32:14 72561 8192000000 10058195312 10528616448 1099716336 11.835 98 13 26 0 1145136576 42999648 2420592
04:34:27 10002 8192000000 10099367296 10541723648 1132386896 12.144 100 37 72 1 1198302784 58093152 7822736
04:35:53 23089 8192000000 10159851264 10596159488 1123301904 12.058 99 42 84 0 1243286704 111497664 8487136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-04-2022 04:38:53 to 01-04-2022 04:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 01-04-2022 04:39:11 to 01-04-2022 04:39:12.

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          218296
MON$OLDEST_ACTIVE               224078
MON$OLDEST_SNAPSHOT             224078
MON$NEXT_TRANSACTION            224078
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                       2073120
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:309: 
:310: mscs-test-161.budzdorov.ru    Fri Apr  1 04:37:29 2022
:311:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:312: 
--- end of diff output ---

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

01.04.22 04:39:13. 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-04-01 04:39:16 No severe PSQL-related problems occured
01.04.22 04:39:17. End of report.