Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.07.2022 07:48
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 15959528 kB
MemAvailable 31153092 kB
Buffers 0 kB
Cached 15719424 kB
SwapCached 2492 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-07-01 07:47:29
DTS_END                         2022-07-01 07:47:29.0600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40060
TRN_ID                          214699
ATT_ID                          573
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: 9177.50 22138 1101300
customer order: creation 165.93 450 19911
customer order: refuse 14.15 387 1698
order to supplier: creation 44.35 1286 5322
order to supplier: removal 3.45 156734 414
invoice (draft): creation 44.64 2840 5357
invoice (draft): removal 52.20 923 6264
invoice accept: apply 41.03 25496 4923
invoice accept: cancel 46.91 1030 5629
customer reserve: creation 3842.49 242 461099
customer reserve: removal 4640.37 91 556844
realization accept: apply 81.98 266 9837
realization accept: cancel 76.14 153 9137
payment from customer: creation 57.00 152 6840
payment from customer: removal 5.47 147 656
payment to supplier: creation 46.58 189 5590
payment to supplier: removal 3.36 126 403
service: total inventory turnovers 3.75 3695 450
service: total monetary turnovers 6.88 388 825
service: aggregate perf. data 0.80 15588 96
service: refresh index statistics 0.04 232576 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-07-2022 07:48:42 to 01-07-2022 07:48:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8738
TEST_TIME 2 6753
TEST_TIME 3 5916
TEST_TIME 4 5382
TEST_TIME 5 5319
TEST_TIME 6 5371
TEST_TIME 7 5353
TEST_TIME 8 5412
TEST_TIME 9 5627
TEST_TIME 10 5770
TEST_TIME 11 5873
TEST_TIME 12 5948
TEST_TIME 13 6402
TEST_TIME 14 6649
TEST_TIME 15 7064
TEST_TIME 16 7094
TEST_TIME 17 7164
TEST_TIME 18 7295
TEST_TIME 19 7526
TEST_TIME 20 8012
TEST_TIME 21 8329
TEST_TIME 22 8345
TEST_TIME 23 8377
TEST_TIME 24 8316
TEST_TIME 25 8789
TEST_TIME 26 9078
TEST_TIME 27 9209
TEST_TIME 28 9928
TEST_TIME 29 9978
TEST_TIME 30 10053
TEST_TIME 31 10330
TEST_TIME 32 10311
TEST_TIME 33 10550
TEST_TIME 34 10822
TEST_TIME 35 10704
TEST_TIME 36 11003
TEST_TIME 37 10867
TEST_TIME 38 10849
TEST_TIME 39 10834
TEST_TIME 40 10736
TEST_TIME 41 10559
TEST_TIME 42 10351
TEST_TIME 43 10338
TEST_TIME 44 10268
TEST_TIME 45 10108
TEST_TIME 46 10111
TEST_TIME 47 10080
TEST_TIME 48 10002
TEST_TIME 49 10062
TEST_TIME 50 9993
TEST_TIME 51 9925
TEST_TIME 52 9889
TEST_TIME 53 9967
TEST_TIME 54 9989
TEST_TIME 55 9962
TEST_TIME 56 9983
TEST_TIME 57 9894
TEST_TIME 58 9946
TEST_TIME 59 10088
TEST_TIME 60 10009
TEST_TIME 61 9963
TEST_TIME 62 10074
TEST_TIME 63 10063
TEST_TIME 64 10056
TEST_TIME 65 10055
TEST_TIME 66 10166
TEST_TIME 67 10258
TEST_TIME 68 10205
TEST_TIME 69 10087
TEST_TIME 70 10107
TEST_TIME 71 9984
TEST_TIME 72 9972
TEST_TIME 73 9945
TEST_TIME 74 9908
TEST_TIME 75 9863
TEST_TIME 76 9781
TEST_TIME 77 9737
TEST_TIME 78 9658
TEST_TIME 79 9708
TEST_TIME 80 9672
TEST_TIME 81 9642
TEST_TIME 82 9631
TEST_TIME 83 9609
TEST_TIME 84 9569
TEST_TIME 85 9591
TEST_TIME 86 9649
TEST_TIME 87 9659
TEST_TIME 88 9644
TEST_TIME 89 9630
TEST_TIME 90 9642
TEST_TIME 91 9716
TEST_TIME 92 9735
TEST_TIME 93 9738
TEST_TIME 94 9644
TEST_TIME 95 9585
TEST_TIME 96 9579
TEST_TIME 97 9518
TEST_TIME 98 9527
TEST_TIME 99 9487
TEST_TIME 100 9449
TEST_TIME 101 9481
TEST_TIME 102 9444
TEST_TIME 103 9422
TEST_TIME 104 9424
TEST_TIME 105 9462
TEST_TIME 106 9478
TEST_TIME 107 9497
TEST_TIME 108 9501
TEST_TIME 109 9526
TEST_TIME 110 9544
TEST_TIME 111 9478
TEST_TIME 112 9431
TEST_TIME 113 9439
TEST_TIME 114 9415
TEST_TIME 115 9379
TEST_TIME 116 9324
TEST_TIME 117 9293
TEST_TIME 118 9255
TEST_TIME 119 9255
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-07-2022 07:48:46 to 01-07-2022 07:48: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 1526589 1526589 0 0.00 0 25763 12 0 0
doc_list_biud 1526571 1526571 0 0.00 0 50501 39 0 0
sp_add_invoice_to_stock 5143 4923 220 4.27 60 205319 25496 0 220
sp_cancel_adding_invoice 5629 5629 0 0.00 6 80464 1031 0 0
sp_cancel_client_order 1698 1698 0 0.00 6 11099 388 0 0
sp_cancel_customer_reserve 556844 556844 0 0.00 1 24563 91 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 12703 147 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 8392 127 0 0
sp_cancel_supplier_invoice 6278 6264 14 0.22 4 50565 924 0 14
sp_cancel_supplier_order 414 414 0 0.00 12 1445039 156734 0 0
sp_cancel_write_off 9137 9137 0 0.00 2 11136 154 0 0
sp_client_order 19911 19911 0 0.00 5 15304 451 0 0
sp_customer_reserve 461099 461099 0 0.00 1 104938 242 0 0
sp_fill_shopping_cart 30572 30572 0 0.00 1 17145 74 0 0
sp_fill_shopping_cart_clo_res 461062 461062 0 0.00 0 16412 17 0 0
sp_get_clo_for_invoice 4881 4881 0 0.00 0 15704 269 0 0
sp_kill_cost_storno 15825 15825 0 0.00 0 11804 68 0 0
sp_kill_qstorno_ret_qs2qd 1129503 1129503 0 0.00 0 50499 40 0 0
sp_kill_qty_storno 594703 594703 0 0.00 0 50499 92 0 0
sp_lock_selected_doc 20202 20202 0 0.00 0 7883 8 0 0
sp_make_cost_storno 27147 27147 0 0.00 0 13797 72 0 0
sp_multiply_rows_for_pdistr 27147 27147 0 0.00 0 25757 35 0 0
sp_multiply_rows_for_qdistr 19910 19910 0 0.00 0 15202 171 0 0
sp_pay_from_customer 6840 6840 0 0.00 5 11153 153 0 0
sp_pay_to_supplier 5590 5590 0 0.00 5 25767 190 0 0
sp_payment_common 12429 12429 0 0.00 2 6372 39 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 1 11085 316 0 0
sp_qd_handle_on_invoice_upd_sts 10509 10509 0 0.00 0 26907 487 0 0
sp_qd_handle_on_reserve_upd_sts 9837 9837 0 0.00 0 17938 66 0 0
sp_reserve_write_off 9837 9837 0 0.00 8 17991 266 0 0
sp_supplier_invoice 5357 5357 0 0.00 9 77642 2840 0 0
sp_supplier_order 5322 5322 0 0.00 7 38407 1287 0 0
srv_aggregate_perf_data 97 96 1 1.03 2237 114195 15588 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 840 108028 36284 0 0
srv_find_qd_qs_mism 1053916 1053916 0 0.00 0 14859 10 0 0
srv_make_invnt_saldo 450 450 0 0.00 40 53258 3696 0 0
srv_make_money_saldo 825 825 0 0.00 1 16934 389 0 0
srv_recalc_idx_stat 5 5 0 0.00 34559 442783 232576 0 0
t$perf-abend:sp_add_invoice_to_stock 220 220 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4930 4930 0 0.00 1 36684 873 0 0
t$perf-norm:sp_cancel_adding_invoice 350 350 0 0.00 1 5495 372 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 4485 25 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 3266 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 1068 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 3389 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 2675 42 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 105356 6992 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 2088 24 0 0
t$perf-norm:sp_client_order 19912 19912 0 0.00 0 8682 39 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 700 13 0 0
t$perf-norm:sp_pay_from_customer 6840 6840 0 0.00 0 7764 21 0 0
t$perf-norm:sp_pay_to_supplier 5590 5590 0 0.00 0 5802 21 0 0
t$perf-norm:sp_reserve_write_off 9837 9837 0 0.00 0 10654 27 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 11911 32 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 5916 31 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 431 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 69 4 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 499 14 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 188 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 192 74 0 0
v_add_invoice_to_stock 10186 9746 440 4.31 0 5456 26 0 440
v_all_customers 36275 36275 0 0.00 0 339 3 0 0
v_all_suppliers 29688 29688 0 0.00 0 82 4 0 0
v_all_wares 39814 39814 0 0.00 0 51 4 0 0
v_cancel_adding_invoice 700 700 0 0.00 0 1042 15 0 0
v_cancel_client_order 3396 3396 0 0.00 0 4787 14 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 4618 13 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 5004 12 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 8315 20 0 0
v_cancel_supplier_invoice 802 774 28 3.49 0 1069 25 0 28
v_cancel_supplier_order 828 828 0 0.00 0 532 9 0 0
v_cancel_write_off 1802 1802 0 0.00 0 1145 21 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 1174 5 0 0
v_max_id_clo_res 959 959 0 0.00 0 2445 12 0 0
v_max_id_ord_sup 5349 5349 0 0.00 0 4401 7 0 0
v_max_non_paid_invoice 1410 1410 0 0.00 0 178 7 0 0
v_max_non_paid_realizn 3576 3576 0 0.00 0 657 6 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 3210 10 0 0
v_min_id_clo_res 959 959 0 0.00 0 3375 21 0 0
v_min_id_ord_sup 5349 5349 0 0.00 0 4225 8 0 0
v_min_non_paid_invoice 5589 5589 0 0.00 0 3491 14 0 0
v_min_non_paid_realizn 6840 6840 0 0.00 0 6362 13 0 0
v_our_firm 10066 10066 0 0.00 0 177 4 0 0
v_reserve_write_off 19672 19672 0 0.00 0 8318 13 0 0
x_lock_depdocs_on_canc_invoice 11506 11506 0 0.00 0 15856 111 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 13453 342 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 2 33152 1131 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 4 76068 2650 0 0
x_make_qstorno_2100_3300 461097 461097 0 0.00 0 104935 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-07-2022 07:48:50 to 01-07-2022 07:48: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
05:17:32 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:33 24043 8192000000 9707664208 10008006656 788458048 8.779 100 78 156 0 814725264 7476960 18790256
05:20:00 24011 8192000000 9770932752 10079494144 840643904 9.306 100 91 182 0 874937328 12690000 21603424
05:21:39 11046 8192000000 9807459904 10129928192 873598624 9.636 100 78 153 0 915986656 25107952 17280080
05:23:05 7688 8192000000 9882248832 10220912640 929268800 10.187 100 78 156 0 992375472 46447264 16659408
05:24:14 5361 8192000000 9926110896 10276806656 963954592 10.528 100 81 159 0 1043810512 62990896 16865024
05:25:23 2558 8192000000 9964194576 10316767232 993946704 10.820 100 86 168 0 1079959712 69220768 16792240
05:26:27 1493 8192000000 9998207712 10361524224 1015136480 11.025 100 94 185 1 1113356128 80881952 17337696
05:27:30 1134 8192000000 10011611072 10378661888 1041035744 11.275 100 94 182 1 1140349424 80744960 18568720
05:28:33 1027 8192000000 10019054128 10389696512 1057279440 11.430 100 96 184 0 1139681808 62675072 19727296
05:29:35 9858 8192000000 10030949840 10405666816 1061319472 11.469 100 88 162 2 1143621136 66486752 15814912
05:30:46 938 8192000000 10049936704 10433900544 1069049664 11.543 100 85 162 1 1165511776 80196416 16265696
05:31:49 1053 8192000000 10049992464 10432856064 1074897456 11.599 100 95 186 0 1171369712 76406416 20065840
05:32:51 1232 8192000000 10061000880 10448609280 1086070864 11.705 100 97 177 0 1185198656 79744544 19383248
05:33:53 1146 8192000000 10048934944 10435145728 1086372528 11.708 100 85 153 2 1169650368 66656096 16621744
05:34:55 919 8192000000 10053557360 10443673600 1090682576 11.749 100 95 180 1 1179062288 69291728 19087984
05:36:09 1719 8192000000 10035873936 10425032704 1082900624 11.675 99 77 153 1 1150816720 53021424 14894672
05:37:12 1934 8192000000 10074347408 10464993280 1096113472 11.801 100 78 150 2 1189207232 77772016 15321744
05:38:15 1537 8192000000 10058799152 10445598720 1090183184 11.744 100 92 174 2 1180876336 71117968 19575184
05:39:17 997 8192000000 10055468336 10444627968 1095918736 11.799 100 91 169 2 1180530656 66366928 18244992
05:40:19 3396 8192000000 10016720800 10403565568 1079323328 11.641 99 64 122 0 1135610736 44631792 11655616
05:41:24 1031 8192000000 10037515904 10420604928 1070175216 11.554 99 93 173 1 1160643760 72659024 17809520
05:42:27 1013 8192000000 10056759120 10445025280 1077917712 11.628 100 91 169 2 1180657952 85011152 17729088
05:43:28 1217 8192000000 10024150736 10402062336 1063078576 11.486 100 98 188 1 1147234608 65173728 18982304
05:44:30 7686 8192000000 10000379344 10390331392 1038853920 11.254 99 70 138 1 1113556592 60657248 14045424
05:45:40 1179 8192000000 9971114672 10347098112 1037288512 11.239 99 81 147 1 1091836976 38994976 15553488
05:46:44 1465 8192000000 9991083440 10370560000 1027021584 11.140 99 76 147 1 1105969856 64174256 14774016
05:47:47 4791 8192000000 10017704464 10400096256 1056213024 11.420 100 76 145 0 1140074384 68994208 14867152
05:48:58 15776 8192000000 10018331152 10404200448 1067556144 11.529 100 63 120 0 1145502176 65492864 12453168
05:50:21 1288 8192000000 10051045808 10434678784 1089403424 11.737 100 81 147 4 1170378608 67204256 13770928
05:51:27 16580 8192000000 10115827616 10507661312 1103227984 11.868 100 77 146 1 1222376192 103628160 15520048
05:52:53 834 8192000000 10141240832 10533588992 1116197104 11.991 100 87 169 0 1261080288 123991584 20891600
05:53:59 7855 8192000000 10183079536 10585534464 1122707488 12.053 100 66 110 1 1297000992 161712080 12581424
05:55:11 5417 8192000000 10220015488 10619117568 1133855072 12.158 100 67 129 1 1340066928 192702224 13509632
05:56:20 1658 8192000000 10263124432 10658238464 1151167856 12.320 100 82 160 3 1377574672 208590112 17816704
05:57:26 944 8192000000 10312177840 10706370560 1157681328 12.382 100 96 186 0 1427458192 248677440 21099424
05:58:30 832 8192000000 10340769952 10737909760 1165148048 12.451 100 94 186 2 1451836272 265586912 21101312
05:59:34 1065 8192000000 10372186432 10785992704 1170248496 12.499 100 99 192 1 1485932912 294303168 21381248
06:00:38 1363 8192000000 10386104000 10802868224 1169510304 12.492 100 80 153 0 1498978272 311375648 18092320
06:01:43 1370 8192000000 10366526416 10828386304 1178425536 12.576 100 94 181 2 1476747936 277092096 21230304
06:02:48 1454 8192000000 10399274224 10810306560 1184328112 12.631 100 86 172 0 1506717232 303556624 18832496
06:03:54 1722 8192000000 10428082416 10837020672 1174463680 12.539 100 96 188 2 1525940672 328249872 23227120
06:04:59 2594 8192000000 10438900240 10866290688 1187505920 12.660 100 91 178 0 1547477472 339461776 20509776
06:06:05 1987 8192000000 10422553584 10895548416 1183873568 12.626 99 92 180 1 1526099952 320154928 22071456
06:07:10 1626 8192000000 10436266512 10896715776 1187865088 12.663 100 88 163 0 1538535984 330737264 19933632
06:08:14 8873 8192000000 10451979872 10912952320 1195562304 12.735 100 60 120 0 1579225552 368178832 15484416
06:09:28 7402 8192000000 10442166112 10873958400 1192915504 12.710 100 53 105 0 1553256416 346774944 13565968
06:10:38 4269 8192000000 10460491856 10916167680 1185995504 12.646 100 57 114 0 1567176704 366306544 14874656
06:11:45 7527 8192000000 10451668032 10922491904 1160302272 12.406 99 45 89 1 1551713008 379308192 12102544
06:12:54 17898 8192000000 10396706960 10884202496 1166372736 12.463 99 41 75 1 1492848800 316548576 9927488
06:14:18 6882 8192000000 10409903520 10850672640 1161216688 12.415 100 37 71 0 1510966656 340065312 9684656
06:15:26 4112 8192000000 10282880880 10776539136 1164723072 12.447 100 43 81 0 1388357248 213974864 9659312
06:16:56 22596 8192000000 10266777632 10726625280 1151694544 12.325 99 29 54 0 1391608464 233172208 6741712
06:18:31 10285 8192000000 10228765536 10664419328 1150938336 12.318 100 43 84 0 1326455952 165975024 9542592
06:19:47 24973 8192000000 10230060752 10675458048 1152996592 12.338 100 20 38 0 1334359424 176963568 4399264
06:21:13 58754 8192000000 10131878864 10627624960 1103777888 11.873 96 18 37 0 1212922432 105133136 4011408
06:23:15 36510 8192000000 10091167984 10605301760 1125967696 12.083 99 8 16 0 1196593984 68949616 1676672
06:24:54 54965 8192000000 10075509200 10523353088 1109017472 11.923 97 36 67 1 1143653520 28765568 5870480
06:26:56 107515 8192000000 10060501008 10513346560 1090815760 11.750 96 16 30 0 1123895360 30026240 3053360
06:30:02 41515 8192000000 10073584384 10538553344 1111706736 11.949 98 41 74 0 1142914800 23223952 7984112
06:31:47 77260 8192000000 10110346112 10540036096 1128144224 12.104 100 51 98 0 1202987024 65381072 9461728
06:34:06 16324 8192000000 10097029040 10550640640 1129283632 12.115 99 29 58 0 1201189392 65945312 5960448
06:35:29 16686 8192000000 10121701648 10556829696 1139735504 12.213 100 55 102 3 1214930160 63714544 11480112
06:36:50 27304 8192000000 10148968464 10647142400 1143841488 12.252 100 25 48 0 1262701840 113520704 5339648
06:38:34 76684 8192000000 10125508656 10601500672 1118981808 12.017 99 14 29 0 1229220160 107351216 2887136
06:40:51 51665 8192000000 10116903280 10565419008 1122742336 12.053 100 17 34 0 1213887152 87166768 3978048
06:42:44 60463 8192000000 10074018208 10525057024 1092612800 11.767 97 24 48 0 1148060048 50195904 5251344
06:44:49 23690 8192000000 10161318976 10595446784 1124735424 12.072 99 48 96 1 1235185008 99994592 10454992
06:47:26 51805 8192000000 10133855568 10566680576 1145694656 12.269 100 28 54 0 1202628128 51369696 5563776
06:49:19 79259 8192000000 10139299200 10598363136 1129424576 12.116 98 20 40 0 1215225120 81776704 4023840
06:51:39 75884 8192000000 10223778336 10721460224 1165236224 12.452 100 18 36 0 1334894256 164935952 4722080
06:53:56 91190 8192000000 10092735296 10584858624 1123004272 12.055 97 19 38 0 1169679920 42892960 3782688
06:56:30 98955 8192000000 10071613056 10563526656 1111694624 11.948 97 29 48 0 1160699584 43323040 5681920
06:59:10 63669 8192000000 10079276560 10572677120 1140194816 12.217 100 25 50 0 1200775568 55052560 5528192
07:01:18 48090 8192000000 10089073040 10526679040 1129247968 12.114 99 23 45 0 1196230208 62257440 4724800
07:03:41 79348 8192000000 10098604080 10574888960 1126023104 12.084 99 13 26 0 1202465904 73665840 2776960
07:06:11 25142 8192000000 10066519808 10522247168 1128821536 12.110 99 23 46 0 1164802016 31697680 4282800
07:07:37 50090 8192000000 10055927760 10549010432 1133286480 12.152 100 12 24 0 1169674160 33946416 2441264
07:09:28 51911 8192000000 10062000192 10485100544 1078251744 11.631 96 48 90 0 1121291040 33954304 9084992
07:11:20 25789 8192000000 10106518272 10535456768 1107620928 11.910 99 44 87 0 1184230304 68065376 8544000
07:12:47 41587 8192000000 10113064752 10581303296 1108825552 11.921 99 29 58 0 1215741360 100928704 5987104
07:14:34 30082 8192000000 10071879536 10515365888 1119741120 12.025 100 44 87 0 1166443200 37436256 9265824
07:16:08 34751 8192000000 10101449328 10555838464 1101952480 11.856 98 28 56 0 1177136000 69593984 5589536
07:17:45 40759 8192000000 10070790064 10547982336 1093328336 11.774 97 23 47 0 1167925296 70026192 4570768
07:19:26 52578 8192000000 10088109552 10585980928 1100403968 11.841 97 39 74 0 1156333136 48637104 7292064
07:21:20 78837 8192000000 10070694224 10517725184 1108392576 11.917 98 27 54 0 1152078496 38110384 5575536
07:23:38 73483 8192000000 10114130416 10595291136 1113347904 11.964 98 13 26 0 1186954416 71089472 2517040
07:25:52 101641 8192000000 10101144720 10550108160 1091138256 11.753 96 13 24 1 1146917456 53065648 2713552
07:28:34 60147 8192000000 10110663152 10561769472 1103548672 11.871 97 32 63 0 1177516112 66029952 7937488
07:30:35 48663 8192000000 10147416976 10606989312 1136955344 12.187 100 25 47 0 1236502464 94205648 5341472
07:32:24 16030 8192000000 10132209488 10568413184 1128763680 12.110 99 44 88 1 1213201136 75153280 9284176
07:33:41 71824 8192000000 10097564912 10557599744 1098157696 11.820 97 40 80 0 1163498416 57690576 7650144
07:35:55 38920 8192000000 10142757152 10606395392 1135389424 12.172 100 35 70 0 1227088064 84747168 6951472
07:38:16 108027 8192000000 10102323280 10545766400 1127319712 12.096 99 50 96 0 1211894464 74112288 10462464
07:41:08 62538 8192000000 10047621152 10522140672 1111826240 11.950 98 5 10 0 1167316336 54443328 1046768
07:43:11 44523 8192000000 10055246368 10517745664 1131104384 12.132 100 13 26 0 1160876544 27240896 2531264
07:44:56 81678 8192000000 10055971552 10521780224 1085931616 11.704 96 12 25 0 1122403328 33894384 2577328
07:47:18 15369 8192000000 8935770784 9133195264 109999984 1.324 10 1 2 0 113005904 2970512 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-07-2022 07:48:56 to 01-07-2022 07:49:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 220 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:02, from 01-07-2022 07:49:13 to 01-07-2022 07:49: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          215270
MON$OLDEST_ACTIVE               215271
MON$OLDEST_SNAPSHOT             215271
MON$NEXT_TRANSACTION            215271
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                       2057120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

01.07.22 07:49: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-07-01 07:49:18 No severe PSQL-related problems occured
01.07.22 07:49:20. End of report.