Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.02.2023 04:39
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 14967780 kB
MemAvailable 30396448 kB
Buffers 20 kB
Cached 16691968 kB
SwapCached 3140 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.3
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 2023-02-23 04:37:26
DTS_END                         2023-02-23 04:37:26.7870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44344
TRN_ID                          223988
ATT_ID                          632
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: 9100.97 21079 1092116
customer order: creation 171.60 460 20592
customer order: refuse 14.63 406 1755
order to supplier: creation 46.93 1368 5631
order to supplier: removal 3.63 139541 435
invoice (draft): creation 45.08 2983 5410
invoice (draft): removal 50.94 936 6113
invoice accept: apply 42.08 24918 5049
invoice accept: cancel 45.96 1208 5515
customer reserve: creation 3861.57 240 463388
customer reserve: removal 4528.73 88 543447
realization accept: apply 83.82 282 10058
realization accept: cancel 76.47 162 9177
payment from customer: creation 59.10 182 7092
payment from customer: removal 5.95 106 714
payment to supplier: creation 49.32 188 5918
payment to supplier: removal 3.26 145 391
service: total inventory turnovers 3.84 4280 461
service: total monetary turnovers 7.07 436 848
service: aggregate perf. data 0.98 14936 117
service: refresh index statistics 0.04 228717 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-02-2023 04:39:25 to 23-02-2023 04:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8980
TEST_TIME 2 7559
TEST_TIME 3 7274
TEST_TIME 4 6514
TEST_TIME 5 6597
TEST_TIME 6 6582
TEST_TIME 7 6421
TEST_TIME 8 6314
TEST_TIME 9 7042
TEST_TIME 10 7287
TEST_TIME 11 7422
TEST_TIME 12 7370
TEST_TIME 13 7289
TEST_TIME 14 7427
TEST_TIME 15 7514
TEST_TIME 16 7748
TEST_TIME 17 7873
TEST_TIME 18 7996
TEST_TIME 19 7971
TEST_TIME 20 7948
TEST_TIME 21 8060
TEST_TIME 22 8106
TEST_TIME 23 8487
TEST_TIME 24 8731
TEST_TIME 25 8935
TEST_TIME 26 9103
TEST_TIME 27 9432
TEST_TIME 28 9879
TEST_TIME 29 10144
TEST_TIME 30 10118
TEST_TIME 31 10522
TEST_TIME 32 10943
TEST_TIME 33 10761
TEST_TIME 34 10693
TEST_TIME 35 10702
TEST_TIME 36 10764
TEST_TIME 37 10558
TEST_TIME 38 10392
TEST_TIME 39 10362
TEST_TIME 40 10284
TEST_TIME 41 10284
TEST_TIME 42 10282
TEST_TIME 43 10118
TEST_TIME 44 9922
TEST_TIME 45 9797
TEST_TIME 46 9810
TEST_TIME 47 9729
TEST_TIME 48 9660
TEST_TIME 49 9618
TEST_TIME 50 9608
TEST_TIME 51 9671
TEST_TIME 52 9627
TEST_TIME 53 9689
TEST_TIME 54 9665
TEST_TIME 55 9635
TEST_TIME 56 9543
TEST_TIME 57 9451
TEST_TIME 58 9623
TEST_TIME 59 9582
TEST_TIME 60 9517
TEST_TIME 61 9529
TEST_TIME 62 9484
TEST_TIME 63 9620
TEST_TIME 64 9637
TEST_TIME 65 9625
TEST_TIME 66 9731
TEST_TIME 67 9653
TEST_TIME 68 9742
TEST_TIME 69 9695
TEST_TIME 70 9758
TEST_TIME 71 9795
TEST_TIME 72 9841
TEST_TIME 73 9783
TEST_TIME 74 9682
TEST_TIME 75 9649
TEST_TIME 76 9609
TEST_TIME 77 9561
TEST_TIME 78 9537
TEST_TIME 79 9497
TEST_TIME 80 9460
TEST_TIME 81 9389
TEST_TIME 82 9326
TEST_TIME 83 9404
TEST_TIME 84 9381
TEST_TIME 85 9345
TEST_TIME 86 9328
TEST_TIME 87 9392
TEST_TIME 88 9384
TEST_TIME 89 9474
TEST_TIME 90 9438
TEST_TIME 91 9495
TEST_TIME 92 9520
TEST_TIME 93 9559
TEST_TIME 94 9595
TEST_TIME 95 9655
TEST_TIME 96 9566
TEST_TIME 97 9521
TEST_TIME 98 9451
TEST_TIME 99 9452
TEST_TIME 100 9429
TEST_TIME 101 9396
TEST_TIME 102 9368
TEST_TIME 103 9356
TEST_TIME 104 9324
TEST_TIME 105 9329
TEST_TIME 106 9361
TEST_TIME 107 9357
TEST_TIME 108 9356
TEST_TIME 109 9347
TEST_TIME 110 9369
TEST_TIME 111 9395
TEST_TIME 112 9391
TEST_TIME 113 9332
TEST_TIME 114 9284
TEST_TIME 115 9244
TEST_TIME 116 9250
TEST_TIME 117 9225
TEST_TIME 118 9230
TEST_TIME 119 9192
TEST_TIME 120 9134

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-02-2023 04:39:29 to 23-02-2023 04:39:32.

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 1520318 1520318 0 0.00 0 15607 12 0 0
doc_list_biud 1520314 1520314 0 0.00 0 24501 38 0 0
sp_add_invoice_to_stock 5124 5049 75 1.46 29 188935 24919 0 75
sp_cancel_adding_invoice 5515 5515 0 0.00 6 81209 1209 0 0
sp_cancel_client_order 1755 1755 0 0.00 6 10121 407 0 0
sp_cancel_customer_reserve 543447 543447 0 0.00 1 24368 88 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 4 2813 106 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 4 6738 146 0 0
sp_cancel_supplier_invoice 6115 6113 2 0.03 3 14962 937 0 2
sp_cancel_supplier_order 435 435 0 0.00 11 1382370 139542 0 0
sp_cancel_write_off 9177 9177 0 0.00 2 10844 163 0 0
sp_client_order 20592 20592 0 0.00 5 26988 460 0 0
sp_customer_reserve 463388 463388 0 0.00 0 117375 240 0 0
sp_fill_shopping_cart 31630 31630 0 0.00 1 8202 79 0 0
sp_fill_shopping_cart_clo_res 463329 463329 0 0.00 0 18005 18 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 19917 273 0 0
sp_kill_cost_storno 15797 15797 0 0.00 0 12152 68 0 0
sp_kill_qstorno_ret_qs2qd 1102619 1102619 0 0.00 0 24347 39 0 0
sp_kill_qty_storno 581486 581486 0 0.00 0 24500 90 0 0
sp_lock_selected_doc 20807 20807 0 0.00 0 8635 8 0 0
sp_make_cost_storno 28056 28056 0 0.00 0 13151 81 0 0
sp_multiply_rows_for_pdistr 28054 28054 0 0.00 0 11681 36 0 0
sp_multiply_rows_for_qdistr 20592 20592 0 0.00 0 26973 175 0 0
sp_pay_from_customer 7092 7092 0 0.00 6 12566 182 0 0
sp_pay_to_supplier 5918 5918 0 0.00 6 12737 188 0 0
sp_payment_common 13009 13009 0 0.00 2 7331 46 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 8161 329 0 0
sp_qd_handle_on_invoice_upd_sts 10502 10502 0 0.00 0 24497 483 0 0
sp_qd_handle_on_reserve_upd_sts 10057 10057 0 0.00 0 22694 63 0 0
sp_reserve_write_off 10058 10058 0 0.00 8 22804 283 0 0
sp_supplier_invoice 5410 5410 0 0.00 7 70358 2983 0 0
sp_supplier_order 5631 5631 0 0.00 9 67665 1369 0 0
srv_aggregate_perf_data 118 117 1 0.84 2533 125574 14937 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 884 183616 36006 0 0
srv_find_qd_qs_mism 1043859 1043859 0 0.00 0 8408 9 0 0
srv_make_invnt_saldo 461 461 0 0.00 50 64354 4280 0 0
srv_make_money_saldo 848 848 0 0.00 1 18399 437 0 0
srv_recalc_idx_stat 5 5 0 0.00 34457 526449 228717 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 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 5051 5051 0 0.00 0 10673 912 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 2 6050 495 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 3757 27 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 2000 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 489 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 363 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1175 36 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 97899 5378 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 1717 19 0 0
t$perf-norm:sp_client_order 20592 20592 0 0.00 0 8163 37 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 8193 26 0 0
t$perf-norm:sp_pay_from_customer 7092 7092 0 0.00 0 4371 18 0 0
t$perf-norm:sp_pay_to_supplier 5918 5918 0 0.00 0 2938 21 0 0
t$perf-norm:sp_reserve_write_off 10058 10058 0 0.00 0 4372 23 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 2647 29 0 0
t$perf-norm:sp_supplier_order 5631 5631 0 0.00 0 8076 34 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 147 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 274 8 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 606 14 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 2802 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 191 90 0 0
v_add_invoice_to_stock 10116 9966 150 1.48 0 10481 26 0 150
v_all_customers 37290 37290 0 0.00 0 62 4 0 0
v_all_suppliers 31028 31028 0 0.00 0 89 4 0 0
v_all_wares 41182 41182 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1664 18 0 0
v_cancel_client_order 3510 3510 0 0.00 0 2315 10 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 289 9 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 212 9 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 747 12 0 0
v_cancel_supplier_invoice 796 792 4 0.50 0 1036 25 0 4
v_cancel_supplier_order 870 870 0 0.00 0 415 9 0 0
v_cancel_write_off 1966 1966 0 0.00 0 8058 32 0 0
v_max_id_clo_ord 5631 5631 0 0.00 0 614 5 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 1413 12 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 7986 8 0 0
v_max_non_paid_invoice 1443 1443 0 0.00 0 77 8 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 7324 10 0 0
v_min_id_clo_ord 5631 5631 0 0.00 0 2759 8 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 1676 11 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 3157 7 0 0
v_min_non_paid_invoice 5918 5918 0 0.00 0 5523 15 0 0
v_min_non_paid_realizn 7091 7091 0 0.00 0 5579 18 0 0
v_our_firm 10538 10538 0 0.00 0 74 5 0 0
v_reserve_write_off 20114 20114 0 0.00 0 6962 12 0 0
x_lock_depdocs_on_canc_invoice 11232 11232 0 0.00 0 21970 128 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 23842 425 0 0
x_make_qstorno_1000_1200 5631 5631 0 0.00 3 67383 1209 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 3 69955 2773 0 0
x_make_qstorno_2100_3300 463386 463386 0 0.00 0 117372 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-02-2023 04:39:33 to 23-02-2023 04:39:37.

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:30 924 8192000000 8912686992 8965881856 14965088 0.182 4 3 6 0 15194720 119968 109664
02:08:32 27514 8192000000 9721360384 10018394112 797577504 8.872 100 87 174 0 826839424 8109088 21152832
02:10:05 20444 8192000000 9747076176 10054746112 824205744 9.141 100 84 168 0 856824560 12230432 20388384
02:11:29 11081 8192000000 9780475488 10095071232 856462880 9.465 100 85 166 1 893278512 17659216 19156416
02:12:48 8053 8192000000 9876742976 10206797824 916888288 10.065 100 85 167 0 979087264 43318880 18880096
02:14:00 3283 8192000000 9932055984 10274574336 962327312 10.512 100 88 176 0 1048009024 67065328 18616384
02:15:07 2735 8192000000 9996412880 10354982912 1005061376 10.928 100 88 176 2 1105973376 82414192 18497808
02:16:12 1678 8192000000 10023881696 10387013632 1034409504 11.211 100 98 191 1 1139662816 86124256 19129056
02:17:15 1170 8192000000 10021489504 10395193344 1044312720 11.306 100 97 188 0 1141642368 78717344 18612304
02:18:18 923 8192000000 10029508720 10403627008 1059864976 11.455 100 91 172 3 1153729008 74966336 18897696
02:19:20 1428 8192000000 10050068144 10423459840 1067810192 11.531 100 94 177 0 1171577280 85886144 17880944
02:20:24 1088 8192000000 10036207776 10416074752 1069412128 11.546 100 98 197 0 1158808672 69591744 19804800
02:21:27 1057 8192000000 10041230992 10421657600 1080237392 11.650 100 96 182 1 1160993216 62707072 18048752
02:22:29 1101 8192000000 10066730048 10451353600 1086343472 11.708 100 94 183 2 1190619184 85869584 18406128
02:23:31 1111 8192000000 10055895488 10443866112 1089285920 11.736 100 96 177 5 1181048848 72431840 19331088
02:24:33 1568 8192000000 10069306544 10457612288 1101282400 11.850 100 88 168 2 1190204384 72479744 16442240
02:25:37 2611 8192000000 10081027360 10471911424 1094687520 11.787 100 76 145 0 1198850976 89661152 14502304
02:26:42 1006 8192000000 10056114960 10438037504 1089515056 11.738 100 92 181 0 1176151024 69004816 17631152
02:27:44 1117 8192000000 10054553952 10446913536 1092531312 11.767 100 82 152 3 1178765680 71355216 14879152
02:28:47 1027 8192000000 10034778672 10423402496 1091963936 11.761 100 89 164 2 1159756000 51835360 15956704
02:29:50 1070 8192000000 10048868416 10432245760 1074564176 11.596 99 97 184 0 1172882944 79374832 18943936
02:30:53 1243 8192000000 10017897632 10396680192 1057706560 11.435 100 99 181 1 1146794144 70982560 18105024
02:31:54 3893 8192000000 9998800944 10379554816 1050015440 11.361 100 65 122 2 1120272864 57588784 12668640
02:32:59 3735 8192000000 9963199664 10340081664 1041760528 11.282 100 61 112 1 1090782864 38334112 10688224
02:34:03 8267 8192000000 9977524800 10354393088 1024359088 11.114 99 71 127 2 1098050368 61041984 12649296
02:35:13 868 8192000000 9998299808 10378031104 1052033600 11.380 100 91 174 2 1124216032 53891152 18291280
02:36:15 965 8192000000 9977224688 10348625920 1033631888 11.203 100 92 169 1 1096269408 44603488 18034032
02:37:17 1004 8192000000 10015946608 10392428544 1058330800 11.441 100 98 178 0 1138402768 60259296 19812672
02:38:24 8927 8192000000 10048053344 10438025216 1072330832 11.574 100 62 123 1 1163416560 78855312 12230416
02:39:46 1077 8192000000 10040666624 10420555776 1094908784 11.789 100 89 167 2 1161916944 51118224 15889936
02:40:52 7835 8192000000 10091540368 10487369728 1104205776 11.878 100 63 122 0 1205894256 89327392 12361088
02:42:05 1350 8192000000 10117266336 10508316672 1123434368 12.059 100 84 161 1 1236347328 95275376 17637584
02:43:10 3685 8192000000 10151345744 10550112256 1137882000 12.196 100 72 139 4 1271389536 116519392 16988144
02:44:16 6728 8192000000 10165808304 10566717440 1134673248 12.165 100 60 114 1 1283920736 137222544 12024944
02:45:31 1569 8192000000 10176849120 10588266496 1132390128 12.144 100 78 146 2 1290353200 142302624 15660448
02:46:39 2033 8192000000 10202117072 10622328832 1146229968 12.274 100 89 175 0 1317884896 152595568 19059360
02:47:43 12390 8192000000 10207105824 10617974784 1154539760 12.352 100 64 126 0 1326705824 158955024 13211040
02:48:59 1596 8192000000 10230055664 10626813952 1153196912 12.339 100 93 179 2 1345810784 173171056 19442816
02:50:05 1412 8192000000 10253589216 10659774464 1164031184 12.441 100 73 138 1 1362342432 182968528 15342720
02:51:10 1394 8192000000 10294390688 10696249344 1175108800 12.545 100 89 172 0 1407152896 211957392 20086704
02:52:16 2046 8192000000 10331820672 10740867072 1186006384 12.646 100 90 177 3 1437496432 231267536 20222512
02:53:20 876 8192000000 10360017072 10774024192 1188142416 12.666 100 96 180 1 1467906032 258787872 20975744
02:54:23 1550 8192000000 10396770336 10812493824 1188846768 12.673 100 96 184 0 1505015872 295576256 20592848
02:55:28 1012 8192000000 10390906528 10804985856 1182165248 12.610 100 96 189 0 1501024992 296447424 22412320
02:56:36 1206 8192000000 10441025568 10850938880 1186685440 12.653 100 100 194 1 1546220448 335385472 24149536
02:57:39 874 8192000000 10458220176 10876084224 1178849744 12.579 100 96 189 0 1563688864 363006112 21833008
02:58:42 932 8192000000 10470723616 10895319040 1163981568 12.441 100 98 186 1 1578982768 392553088 22448112
02:59:47 920 8192000000 10490149904 10945941504 1167509456 12.474 100 98 192 0 1597083568 405814800 23759312
03:00:49 8084 8192000000 10455741664 10891513856 1149722208 12.307 100 49 94 1 1557902288 395434944 12745136
03:02:00 7763 8192000000 10445907536 10879320064 1151768112 12.326 100 59 113 0 1548454848 382122032 14564704
03:03:12 1765 8192000000 10416201856 10865561600 1153114528 12.339 100 61 122 1 1516729792 348736864 14878400
03:04:20 12088 8192000000 10388373296 10873167872 1151577008 12.324 100 48 97 0 1494127888 330245424 12305456
03:05:37 21666 8192000000 10315978032 10807697408 1143876736 12.252 100 31 62 0 1438873888 287516704 7480448
03:06:59 34555 8192000000 10273191376 10736553984 1128692304 12.109 99 26 52 0 1375881760 240877568 6311888
03:08:40 35934 8192000000 10086900864 10609463296 1093674544 11.778 97 21 40 0 1207254384 108794704 4785136
03:10:20 10236 8192000000 10069094496 10488700928 1120596752 12.033 99 62 112 3 1151699456 17059184 14043520
03:11:34 44636 8192000000 10144698816 10603929600 1130781632 12.129 99 14 28 0 1238486672 104418112 3286928
03:13:20 89656 8192000000 10081248896 10522066944 1133470880 12.154 100 16 32 0 1189073936 51795568 3807488
03:15:57 96095 8192000000 10054753104 10489536512 1097789200 11.817 97 20 38 0 1141258048 40040016 3428832
03:18:34 37612 8192000000 10078599312 10522415104 1121893392 12.045 99 26 44 2 1181899088 55028864 4976832
03:20:18 73273 8192000000 10084381712 10536296448 1128629328 12.108 100 21 41 0 1185682048 52666816 4385904
03:22:41 9972 8192000000 10150832688 10584731648 1143459744 12.248 100 82 153 2 1242269632 81644848 17165040
03:24:02 40490 8192000000 10115964816 10582740992 1127352416 12.096 99 21 42 0 1221112608 89083680 4676512
03:25:44 40569 8192000000 10161276816 10612645888 1137888448 12.196 100 30 58 1 1265408624 120314320 7205856
03:27:35 39029 8192000000 10154307040 10632691712 1137320320 12.190 100 35 68 1 1263611888 119051696 7239872
03:29:17 92612 8192000000 10098042080 10554167296 1129165248 12.113 99 30 46 2 1199061360 64245968 5650144
03:32:43 24728 8192000000 10097540080 10562048000 1146784352 12.279 100 13 26 1 1209317488 59930672 2602464
03:34:08 86501 8192000000 10140035792 10642010112 1123742736 12.062 98 14 28 0 1216604032 89911008 2950288
03:36:36 29799 8192000000 10089638416 10541580288 1139993792 12.215 100 25 50 0 1186022944 41055616 4973536
03:38:07 30904 8192000000 10097705056 10543869952 1140411840 12.219 99 27 52 0 1196591312 51099824 5079648
03:39:38 46601 8192000000 10119464656 10585284608 1122773376 12.053 97 27 54 0 1239152480 110360192 6018912
03:41:34 28409 8192000000 10115080880 10553495552 1114169200 11.972 98 23 46 0 1157944352 39280352 4494800
03:43:03 74160 8192000000 10083218368 10524188672 1082088688 11.667 95 28 54 0 1151499712 63203792 6207232
03:45:22 45312 8192000000 10083625504 10532397056 1100093120 11.839 98 14 28 0 1171739312 68638256 3007936
03:47:08 17826 8192000000 10105163744 10524954624 1112165168 11.953 99 45 90 1 1179042256 57550240 9326848
03:48:28 55573 8192000000 10108872928 10531414016 1104469856 11.880 98 29 56 0 1169840368 59751920 5618592
03:50:26 97286 8192000000 10099565296 10542325760 1115716608 11.987 99 31 60 2 1179356048 56977184 6662256
03:53:14 29652 8192000000 10083916096 10559016960 1109559152 11.928 98 31 62 0 1171532272 55296272 6676848
03:54:44 65726 8192000000 10068533168 10544128000 1113019136 11.961 99 15 31 0 1200927440 84533664 3374640
03:56:50 54338 8192000000 10085942816 10556694528 1116886640 11.998 100 21 42 0 1195054256 73127184 5040432
03:58:51 57333 8192000000 10114043344 10561302528 1114261984 11.973 99 15 30 0 1213627824 95489504 3876336
04:01:00 78843 8192000000 10108979040 10547154944 1091230624 11.754 96 18 36 0 1173551120 78093696 4226800
04:03:19 29564 8192000000 10171817632 10622185472 1133817456 12.157 100 41 82 0 1242601984 99896416 8888112
04:05:09 52559 8192000000 10144702592 10593329152 1118729856 12.015 98 42 84 0 1213338992 86217568 8391568
04:07:02 23077 8192000000 10100464688 10538307584 1138188720 12.198 100 41 79 1 1205758672 59260544 8309408
04:08:36 30138 8192000000 10137051168 10628173824 1135115808 12.170 100 23 46 0 1225884080 86267648 4500624
04:10:09 26473 8192000000 10083710000 10521882624 1114328960 11.973 98 41 79 1 1162400752 40203536 7868256
04:11:38 38140 8192000000 10131519872 10633306112 1138192848 12.199 100 32 64 0 1252435392 107623808 6618736
04:13:27 88637 8192000000 10074058096 10557263872 1128695472 12.109 99 19 38 0 1157922080 25376656 3849952
04:17:27 66710 8192000000 10084453696 10545864704 1137484192 12.192 99 20 40 0 1172165680 30836832 3844656
04:19:53 31717 8192000000 10125883728 10568216576 1145751968 12.270 100 53 105 2 1229074064 71503392 11818704
04:21:25 82287 8192000000 10127324528 10585391104 1151264224 12.321 100 26 52 0 1232031376 75025216 5741936
04:23:55 42395 8192000000 10133753136 10613592064 1146306128 12.275 99 29 58 0 1224901072 72858960 5735984
04:25:40 69731 8192000000 10103376176 10570702848 1122703568 12.053 97 17 34 0 1157480176 32009584 2767024
04:27:51 183616 8192000000 10071112000 10531336192 1116345824 11.992 97 16 32 0 1159673184 40282544 3044816
04:32:19 67279 8192000000 10059346720 10564055040 1130550320 12.127 99 22 43 0 1189957392 54740976 4666096
04:34:43 75019 8192000000 10057254880 10558914560 1097280432 11.812 97 10 20 0 1146531968 47254528 1997008
04:36:58 34253 8192000000 9287204816 9613737984 438822320 5.084 38 12 24 0 472825344 31346016 2657008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 23-02-2023 04:39:39 to 23-02-2023 04:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 75 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-02-2023 04:39:57 to 23-02-2023 04:39:59.

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          224748
MON$OLDEST_ACTIVE               224749
MON$OLDEST_SNAPSHOT             224749
MON$NEXT_TRANSACTION            224749
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                       2063648
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

23.02.23 04:40:00. 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
2023-02-23 04:40:02 No severe PSQL-related problems occured
23.02.23 04:40:04. End of report.