Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 07: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 15816292 kB
MemAvailable 31040728 kB
Buffers 0 kB
Cached 15864444 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-20 07:37:20
DTS_END                         2022-07-20 07:37:20.7340
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/44574
TRN_ID                          219170
ATT_ID                          626
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: 8862.54 22438 1063505
customer order: creation 172.05 496 20646
customer order: refuse 14.78 372 1773
order to supplier: creation 45.53 1396 5463
order to supplier: removal 3.52 153958 422
invoice (draft): creation 46.88 3348 5626
invoice (draft): removal 49.05 975 5886
invoice accept: apply 41.18 24690 4942
invoice accept: cancel 44.21 1167 5305
customer reserve: creation 3800.43 236 456051
customer reserve: removal 4356.48 98 522778
realization accept: apply 81.75 302 9810
realization accept: cancel 78.31 160 9397
payment from customer: creation 58.33 182 6999
payment from customer: removal 5.68 117 682
payment to supplier: creation 48.61 196 5833
payment to supplier: removal 3.26 124 391
service: total inventory turnovers 4.02 3753 482
service: total monetary turnovers 7.51 351 901
service: aggregate perf. data 0.94 13905 113
service: refresh index statistics 0.04 242931 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-07-2022 07:38:30 to 20-07-2022 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6090
TEST_TIME 2 5423
TEST_TIME 3 5568
TEST_TIME 4 5869
TEST_TIME 5 5817
TEST_TIME 6 6130
TEST_TIME 7 6244
TEST_TIME 8 6279
TEST_TIME 9 6457
TEST_TIME 10 6516
TEST_TIME 11 6557
TEST_TIME 12 6750
TEST_TIME 13 7141
TEST_TIME 14 7558
TEST_TIME 15 7564
TEST_TIME 16 7585
TEST_TIME 17 7561
TEST_TIME 18 7542
TEST_TIME 19 7629
TEST_TIME 20 7704
TEST_TIME 21 7676
TEST_TIME 22 7969
TEST_TIME 23 8377
TEST_TIME 24 8643
TEST_TIME 25 8625
TEST_TIME 26 8540
TEST_TIME 27 8736
TEST_TIME 28 8867
TEST_TIME 29 9776
TEST_TIME 30 10047
TEST_TIME 31 10658
TEST_TIME 32 11098
TEST_TIME 33 10975
TEST_TIME 34 10747
TEST_TIME 35 10662
TEST_TIME 36 10641
TEST_TIME 37 10463
TEST_TIME 38 10437
TEST_TIME 39 10381
TEST_TIME 40 10356
TEST_TIME 41 10210
TEST_TIME 42 10125
TEST_TIME 43 10063
TEST_TIME 44 9953
TEST_TIME 45 9921
TEST_TIME 46 9865
TEST_TIME 47 9790
TEST_TIME 48 9836
TEST_TIME 49 9770
TEST_TIME 50 9715
TEST_TIME 51 9942
TEST_TIME 52 9809
TEST_TIME 53 9683
TEST_TIME 54 9541
TEST_TIME 55 9569
TEST_TIME 56 9444
TEST_TIME 57 9416
TEST_TIME 58 9356
TEST_TIME 59 9376
TEST_TIME 60 9358
TEST_TIME 61 9277
TEST_TIME 62 9217
TEST_TIME 63 9181
TEST_TIME 64 9162
TEST_TIME 65 9080
TEST_TIME 66 9078
TEST_TIME 67 9039
TEST_TIME 68 9121
TEST_TIME 69 9122
TEST_TIME 70 9176
TEST_TIME 71 9172
TEST_TIME 72 9124
TEST_TIME 73 9128
TEST_TIME 74 9168
TEST_TIME 75 9183
TEST_TIME 76 9205
TEST_TIME 77 9305
TEST_TIME 78 9312
TEST_TIME 79 9278
TEST_TIME 80 9333
TEST_TIME 81 9231
TEST_TIME 82 9327
TEST_TIME 83 9243
TEST_TIME 84 9233
TEST_TIME 85 9159
TEST_TIME 86 9132
TEST_TIME 87 9062
TEST_TIME 88 9002
TEST_TIME 89 8963
TEST_TIME 90 8938
TEST_TIME 91 8912
TEST_TIME 92 8881
TEST_TIME 93 8895
TEST_TIME 94 8919
TEST_TIME 95 8918
TEST_TIME 96 8904
TEST_TIME 97 8902
TEST_TIME 98 8946
TEST_TIME 99 8940
TEST_TIME 100 8953
TEST_TIME 101 9082
TEST_TIME 102 9042
TEST_TIME 103 9097
TEST_TIME 104 9090
TEST_TIME 105 9064
TEST_TIME 106 9041
TEST_TIME 107 8990
TEST_TIME 108 8956
TEST_TIME 109 8902
TEST_TIME 110 8852
TEST_TIME 111 8837
TEST_TIME 112 8853
TEST_TIME 113 8857
TEST_TIME 114 8885
TEST_TIME 115 8891
TEST_TIME 116 8884
TEST_TIME 117 8899
TEST_TIME 118 8898
TEST_TIME 119 8941
TEST_TIME 120 8879

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-07-2022 07:38:33 to 20-07-2022 07:38:37.

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 1485972 1485972 0 0.00 0 17495 13 0 0
doc_list_biud 1485960 1485960 0 0.00 0 43528 40 0 0
sp_add_invoice_to_stock 5044 4942 102 2.02 34 182709 24691 0 102
sp_cancel_adding_invoice 5305 5305 0 0.00 6 80386 1167 0 0
sp_cancel_client_order 1773 1773 0 0.00 6 8669 372 0 0
sp_cancel_customer_reserve 522778 522778 0 0.00 1 46379 98 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 4 1657 118 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 4 1990 125 0 0
sp_cancel_supplier_invoice 5892 5886 6 0.10 2 43542 975 0 6
sp_cancel_supplier_order 422 422 0 0.00 12 1513464 153959 0 0
sp_cancel_write_off 9397 9397 0 0.00 2 13089 160 0 0
sp_client_order 20646 20646 0 0.00 6 39830 497 0 0
sp_customer_reserve 456051 456051 0 0.00 1 97695 236 0 0
sp_fill_shopping_cart 31712 31712 0 0.00 1 19575 87 0 0
sp_fill_shopping_cart_clo_res 456021 456021 0 0.00 0 13630 18 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 23417 284 0 0
sp_kill_cost_storno 15775 15775 0 0.00 0 10407 71 0 0
sp_kill_qstorno_ret_qs2qd 1061261 1061261 0 0.00 0 43526 42 0 0
sp_kill_qty_storno 560277 560277 0 0.00 0 43527 98 0 0
sp_lock_selected_doc 20480 20480 0 0.00 0 12188 7 0 0
sp_make_cost_storno 27550 27550 0 0.00 0 16333 83 0 0
sp_multiply_rows_for_pdistr 27547 27547 0 0.00 0 10497 41 0 0
sp_multiply_rows_for_qdistr 20644 20644 0 0.00 0 39669 180 0 0
sp_pay_from_customer 6999 6999 0 0.00 6 15971 182 0 0
sp_pay_to_supplier 5833 5833 0 0.00 6 20729 197 0 0
sp_payment_common 12830 12830 0 0.00 2 9767 50 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 8594 310 0 0
sp_qd_handle_on_invoice_upd_sts 10211 10211 0 0.00 0 33250 502 0 0
sp_qd_handle_on_reserve_upd_sts 9810 9810 0 0.00 0 36198 81 0 0
sp_reserve_write_off 9810 9810 0 0.00 8 36338 303 0 0
sp_supplier_invoice 5626 5626 0 0.00 8 106588 3349 0 0
sp_supplier_order 5463 5463 0 0.00 9 121631 1396 0 0
srv_aggregate_perf_data 114 113 1 0.87 2260 222741 13905 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 750 174340 36301 0 0
srv_find_qd_qs_mism 1016189 1016189 0 0.00 0 14069 10 0 0
srv_make_invnt_saldo 482 482 0 0.00 30 104350 3753 0 0
srv_make_money_saldo 901 901 0 0.00 1 16486 352 0 0
srv_recalc_idx_stat 5 5 0 0.00 81586 473501 242931 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4943 4943 0 0.00 1 50696 898 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 3 6311 436 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 7548 30 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 7382 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 1149 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 3121 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 897 31 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 111619 6368 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 7404 28 0 0
t$perf-norm:sp_client_order 20648 20648 0 0.00 0 8088 37 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 1621 16 0 0
t$perf-norm:sp_pay_from_customer 6999 6999 0 0.00 0 8088 23 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 7947 22 0 0
t$perf-norm:sp_reserve_write_off 9810 9810 0 0.00 0 11579 26 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 13404 36 0 0
t$perf-norm:sp_supplier_order 5463 5463 0 0.00 0 2247 28 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 437 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 121 4 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 5047 26 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 1278 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 203 113 0 0
v_add_invoice_to_stock 9997 9793 204 2.04 0 6180 25 0 204
v_all_customers 37838 37838 0 0.00 0 181 4 0 0
v_all_suppliers 31020 31020 0 0.00 0 101 4 0 0
v_all_wares 41273 41273 0 0.00 0 88 4 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1440 16 0 0
v_cancel_client_order 3546 3546 0 0.00 0 1832 10 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 896 11 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 1 1251 11 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 1309 13 0 0
v_cancel_supplier_invoice 834 822 12 1.43 0 1134 25 0 12
v_cancel_supplier_order 844 844 0 0.00 0 1466 13 0 0
v_cancel_write_off 2012 2012 0 0.00 0 3338 24 0 0
v_max_id_clo_ord 5458 5458 0 0.00 0 560 5 0 0
v_max_id_clo_res 960 960 0 0.00 0 2522 15 0 0
v_max_id_ord_sup 5613 5613 0 0.00 0 5330 8 0 0
v_max_non_paid_invoice 1394 1394 0 0.00 0 1318 8 0 0
v_max_non_paid_realizn 3493 3493 0 0.00 0 3727 9 0 0
v_min_id_clo_ord 5458 5458 0 0.00 0 3846 10 0 0
v_min_id_clo_res 960 960 0 0.00 0 1548 9 0 0
v_min_id_ord_sup 5613 5613 0 0.00 0 8015 12 0 0
v_min_non_paid_invoice 5832 5832 0 0.00 0 8193 18 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 9067 21 0 0
v_our_firm 10440 10440 0 0.00 0 60 5 0 0
v_reserve_write_off 19614 19614 0 0.00 0 3857 10 0 0
x_lock_depdocs_on_canc_invoice 10780 10780 0 0.00 0 14303 131 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 13980 344 0 0
x_make_qstorno_1000_1200 5463 5463 0 0.00 3 118382 1236 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 4 106573 3100 0 0
x_make_qstorno_2100_3300 456050 456050 0 0.00 0 97693 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-07-2022 07:38:38 to 20-07-2022 07:38:42.

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:24 7 8192000000 8801197824 8813142016 5971152 0.072 3 1 2 0 6072352 65424 35776
05:08:25 34561 8192000000 9723493168 10025807872 804932864 8.946 100 91 182 0 835301008 7534128 22834016
05:10:15 17861 8192000000 9769098976 10079313920 840056256 9.300 100 90 180 0 875937072 14279920 21600896
05:11:40 9092 8192000000 9816867888 10135547904 867619088 9.576 100 87 174 0 914583472 26897328 20067056
05:12:50 7600 8192000000 9875809264 10207723520 917140112 10.068 100 87 171 0 984867856 49097344 18630400
05:14:04 3505 8192000000 9912423840 10258141184 957111744 10.461 100 83 160 2 1024181680 50284432 16785504
05:15:12 2161 8192000000 9948330560 10301849600 995476560 10.835 100 92 181 0 1067276208 53114544 18685104
05:16:15 2282 8192000000 9998221328 10358431744 1015543648 11.029 100 87 167 0 1118103488 85880960 16678880
05:17:20 904 8192000000 10023427216 10394599424 1043011056 11.294 100 97 191 0 1148117008 86008752 19097200
05:18:22 1223 8192000000 10017661376 10391949312 1051463024 11.375 100 96 184 0 1133412992 64479648 17470320
05:19:26 7844 8192000000 10034245536 10413289472 1066003248 11.514 100 75 142 1 1160661712 81013632 13644832
05:20:35 1232 8192000000 10049938816 10429038592 1070533520 11.557 100 96 186 3 1173031120 82044640 20452960
05:21:37 1083 8192000000 10073907760 10458824704 1080501984 11.652 100 96 185 0 1191791168 93598624 17690560
05:22:39 1075 8192000000 10067060560 10452709376 1085609152 11.701 100 92 173 0 1184269456 81542128 17118176
05:23:41 1114 8192000000 10035308128 10425147392 1087013264 11.714 100 88 162 0 1156571392 53637648 15920480
05:24:43 1045 8192000000 10044350624 10433216512 1090866384 11.751 100 89 173 4 1162257360 53057296 18333680
05:25:46 855 8192000000 10042239952 10431938560 1092996624 11.771 100 89 171 2 1168090864 57112528 17981712
05:26:48 903 8192000000 10050655552 10439360512 1099059008 11.829 100 91 176 1 1173403328 55280352 19063968
05:27:49 1060 8192000000 10072091104 10463592448 1103406400 11.870 100 92 176 4 1195590496 73565584 18618512
05:28:51 1141 8192000000 10035663440 10422284288 1095496528 11.795 100 86 163 0 1155310608 44032704 15781376
05:29:53 1499 8192000000 10014167776 10395828224 1078902832 11.637 99 88 163 2 1133850688 39078016 15869840
05:30:57 3852 8192000000 10022002128 10409283584 1082922512 11.675 100 78 129 4 1146487376 50446256 13118608
05:32:01 4681 8192000000 9983932288 10360573952 1043120640 11.295 98 73 134 0 1112508288 56220624 13167024
05:33:07 3000 8192000000 10017014112 10407972864 1060705872 11.463 100 73 144 0 1145376496 70358160 14312464
05:34:12 1397 8192000000 9977577488 10340945920 1002260032 10.900 99 94 173 1 1101003680 79970544 18773104
05:35:13 2245 8192000000 9958571520 10329391104 1019513776 11.067 99 81 150 1 1077631952 43413856 14704320
05:36:16 863 8192000000 9970192848 10347442176 1045396016 11.316 100 90 176 1 1096361168 33200544 17764608
05:37:18 1956 8192000000 9993781360 10373795840 1057864768 11.436 100 77 141 3 1110904400 39964544 13075088
05:38:27 8400 8192000000 10006520816 10389835776 1058284176 11.440 100 63 124 0 1120332704 49909456 12139072
05:39:42 3837 8192000000 10041455808 10432409600 1073803200 11.588 100 71 130 0 1158596144 71647888 13145056
05:40:53 3320 8192000000 10079968576 10466086912 1094409968 11.785 100 75 142 1 1193237888 83897680 14930240
05:42:00 4229 8192000000 10086476112 10479972352 1088629120 11.730 99 77 146 0 1199635696 95561696 15444880
05:43:10 14597 8192000000 10122577920 10525908992 1108281072 11.916 100 62 120 0 1251813952 130130848 13402032
05:44:28 1489 8192000000 10137758672 10547867648 1125799072 12.082 100 62 109 2 1250892032 112980560 12112400
05:45:38 740 8192000000 10192520624 10584326144 1136932576 12.187 100 95 186 1 1308380752 151211488 20236688
05:46:42 14179 8192000000 10234547776 10644828160 1152013664 12.328 100 64 122 0 1348211344 181981008 14216672
05:48:00 1262 8192000000 10259489776 10658062336 1155975696 12.366 100 84 152 1 1370792432 197179600 17637136
05:49:04 1394 8192000000 10300095168 10721517568 1173697904 12.531 100 92 176 0 1420736032 225253984 21784144
05:50:10 1831 8192000000 10307852752 10724171776 1171118896 12.507 100 76 150 1 1417971488 228902720 17949872
05:51:18 1567 8192000000 10336276752 10772500480 1186430592 12.650 100 91 176 0 1446178896 239532000 20216304
05:52:24 914 8192000000 10359359472 10768924672 1193462432 12.716 100 94 185 0 1473713456 257764288 22486736
05:53:28 1622 8192000000 10410584624 10822873088 1191232768 12.695 100 82 162 0 1515702160 305869584 18599808
05:54:33 1673 8192000000 10460810560 10869858304 1182139424 12.610 99 92 178 1 1553905136 350868176 20897536
05:55:40 756 8192000000 10477335968 10884608000 1179627840 12.587 100 98 190 1 1578455632 376923728 21904064
05:56:43 861 8192000000 10509390320 10941685760 1178256096 12.574 100 98 192 0 1615122576 413754960 23111520
05:57:48 2254 8192000000 10522609440 10945511424 1177056160 12.563 100 87 174 0 1626167520 428146096 20965264
05:58:54 2883 8192000000 10503428800 10989887488 1176070704 12.554 99 98 190 2 1600109920 401586336 22452880
05:59:59 1179 8192000000 10535388976 11014197248 1191355136 12.696 100 80 160 0 1640648000 429804256 19488608
06:01:05 4912 8192000000 10528478560 10989989888 1175796432 12.551 100 54 108 0 1632624912 442784448 14044032
06:02:14 13430 8192000000 10491835168 10955038720 1166177616 12.461 100 42 83 1 1593765600 416469184 11118800
06:03:28 12471 8192000000 10544788000 10998210560 1169256704 12.490 100 37 72 2 1650973744 470823632 10893408
06:04:56 14015 8192000000 10495222848 10985504768 1156902240 12.374 100 37 71 0 1618637600 452458512 9276848
06:06:16 15243 8192000000 10367946000 10895081472 1155570784 12.362 100 45 88 1 1498501280 331703008 11227488
06:07:33 40089 8192000000 10290307760 10826280960 1154602432 12.353 100 29 58 0 1430264608 268177152 7485024
06:09:30 23059 8192000000 10077336544 10599305216 1117029424 11.999 98 15 28 0 1173433328 53016688 3387216
06:10:55 51525 8192000000 10057431280 10501812224 1100906592 11.846 98 32 46 3 1175116624 70178480 4031552
06:12:56 59653 8192000000 10089337280 10592206848 1126469984 12.088 100 13 26 0 1195740752 66031984 3238784
06:14:58 89103 8192000000 10095766320 10559447040 1144132992 12.254 100 13 26 0 1245546496 99012576 2400928
06:17:30 86769 8192000000 10090973296 10534072320 1139660064 12.212 99 37 66 1 1185639248 38552672 7426512
06:20:09 53134 8192000000 10089885872 10549096448 1123938784 12.064 98 25 50 0 1168192496 38809568 5444144
06:22:13 39309 8192000000 10081716048 10521395200 1144274128 12.256 100 18 36 0 1177431184 29108800 4048256
06:24:09 13553 8192000000 10100189680 10521055232 1138619536 12.203 99 64 123 2 1184613568 32112944 13881088
06:25:25 52090 8192000000 10084949280 10565394432 1139358896 12.209 100 10 20 0 1210943984 69575712 2009376
06:27:18 138637 8192000000 10071889712 10574139392 1097656256 11.815 96 29 56 2 1176414880 72070288 6688336
06:30:37 174340 8192000000 10054868112 10488025088 1094331376 11.784 96 28 56 1 1152959296 51761184 6866736
06:34:37 45915 8192000000 10096909328 10535960576 1122103968 12.047 98 51 102 0 1186644912 53720400 10820544
06:36:26 32303 8192000000 10065041120 10533310464 1102449664 11.861 97 35 71 0 1149577680 40426304 6701712
06:38:03 47880 8192000000 10074900576 10519601152 1129162688 12.113 99 12 24 0 1161341440 29645664 2533088
06:40:05 61543 8192000000 10118506496 10592468992 1154546480 12.352 100 22 44 0 1231825344 71677552 5601312
06:42:16 76713 8192000000 10091445104 10579673088 1146409648 12.276 99 23 44 1 1199819584 48556304 4853632
06:44:35 69031 8192000000 10090123552 10561458176 1101191808 11.849 96 20 38 1 1168199600 63084304 3923488
06:46:59 69740 8192000000 10078392784 10519351296 1097962992 11.818 97 19 39 0 1146908752 45313488 3632272
06:49:12 28301 8192000000 10103073216 10539724800 1109249152 11.925 98 41 81 0 1184674096 67425600 7999344
06:50:43 25315 8192000000 10084543184 10516172800 1111404896 11.946 99 37 75 0 1197923856 79153712 7365248
06:52:09 19830 8192000000 10118765104 10575880192 1118292656 12.011 99 37 74 0 1212068608 86197824 7578128
06:53:31 38977 8192000000 10141025184 10596491264 1122950608 12.055 99 38 77 0 1228813024 97848112 8014304
06:55:10 57628 8192000000 10110139584 10538168320 1122486864 12.050 99 19 37 0 1168307056 41816144 4004048
06:57:09 123814 8192000000 10094365920 10567163904 1096291328 11.802 97 33 67 0 1196469024 92698048 7479648
07:00:50 97674 8192000000 10035866960 10512850944 1105906224 11.894 98 14 28 0 1148370272 39410080 3053968
07:03:28 67522 8192000000 10055004016 10510372864 1100499392 11.842 98 10 20 0 1139571920 36990752 2081776
07:05:54 68101 8192000000 10025888304 10513403904 1089641392 11.739 98 9 18 0 1129051152 37799504 1610256
07:08:04 28364 8192000000 10025331760 10464530432 1092791680 11.769 98 19 37 0 1119590832 22978224 3820928
07:09:33 32114 8192000000 10113164928 10550185984 1122437344 12.050 99 31 62 0 1200765616 71907984 6420288
07:11:18 40557 8192000000 10117100240 10572374016 1131362704 12.134 99 27 54 0 1221994464 84650128 5981632
07:13:06 28800 8192000000 10160381472 10597900288 1142177312 12.236 99 48 87 0 1248654384 96367920 10109152
07:14:37 29738 8192000000 10156160448 10602283008 1143106944 12.245 99 43 80 3 1241877568 90737264 8033360
07:16:24 31799 8192000000 10169086112 10619809792 1144647632 12.259 100 31 62 0 1273395872 121995872 6752368
07:17:56 48188 8192000000 10105720672 10609549312 1141297456 12.228 100 34 68 0 1270740864 122546544 6896864
07:19:55 15287 8192000000 10153025344 10577944576 1137071104 12.188 99 57 112 0 1224542128 76354752 11116272
07:21:51 38263 8192000000 10109999648 10558197760 1136651296 12.184 99 35 66 0 1203829616 59838032 7340288
07:23:41 52799 8192000000 10088037696 10544295936 1133303056 12.152 99 54 104 1 1185443968 41038272 11102640
07:25:34 64209 8192000000 10108425584 10569658368 1137986160 12.197 99 8 16 0 1217638432 77745216 1907056
07:27:38 82783 8192000000 10173369696 10648305664 1154964288 12.356 100 15 30 0 1274338480 116253600 3120592
07:30:12 63769 8192000000 10112733952 10615455744 1142421216 12.238 99 21 42 0 1236996656 90130768 4444672
07:32:43 18571 8192000000 10130053952 10568736768 1137079968 12.188 99 31 61 0 1213764336 70471232 6213136
07:34:02 19969 8192000000 10129841216 10600239104 1150218720 12.312 100 41 78 0 1246344336 87550704 8574912
07:35:25 23549 8192000000 10166984960 10623946752 1151549344 12.324 100 52 104 1 1266831120 103368640 11913136
07:36:54 41383 8192000000 9075400688 9372782592 222905360 2.648 20 2 4 0 261958688 38673600 379728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-07-2022 07:38:44 to 20-07-2022 07:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 102 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-07-2022 07:39:01 to 20-07-2022 07:39:03.

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          219478
MON$OLDEST_ACTIVE               219479
MON$OLDEST_SNAPSHOT             219479
MON$NEXT_TRANSACTION            219479
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                       2060064
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.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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'

20.07.22 07:39:04. 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-20 07:39:06 No severe PSQL-related problems occured
20.07.22 07:39:08. End of report.