Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2023 13: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 15099608 kB
MemAvailable 30438288 kB
Buffers 0 kB
Cached 16624664 kB
SwapCached 4400 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-01-09 13:37:25
DTS_END                         2023-01-09 13:37:25.3580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37026
TRN_ID                          217225
ATT_ID                          572
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: 9013.88 20304 1081665
customer order: creation 167.16 484 20059
customer order: refuse 13.96 410 1675
order to supplier: creation 43.47 1308 5216
order to supplier: removal 3.41 178731 409
invoice (draft): creation 43.78 3196 5254
invoice (draft): removal 52.06 922 6247
invoice accept: apply 39.73 25253 4767
invoice accept: cancel 47.69 1201 5723
customer reserve: creation 3712.09 238 445451
customer reserve: removal 4593.92 105 551270
realization accept: apply 79.72 293 9567
realization accept: cancel 90.68 173 10882
payment from customer: creation 57.62 160 6914
payment from customer: removal 5.41 125 649
payment to supplier: creation 48.01 189 5761
payment to supplier: removal 3.23 110 388
service: total inventory turnovers 4.02 3744 482
service: total monetary turnovers 6.99 320 839
service: aggregate perf. data 0.89 16503 107
service: refresh index statistics 0.04 172621 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-01-2023 13:39:08 to 09-01-2023 13:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8214
TEST_TIME 2 6796
TEST_TIME 3 6149
TEST_TIME 4 5059
TEST_TIME 5 5448
TEST_TIME 6 5092
TEST_TIME 7 5182
TEST_TIME 8 5174
TEST_TIME 9 5447
TEST_TIME 10 5698
TEST_TIME 11 5757
TEST_TIME 12 6112
TEST_TIME 13 6348
TEST_TIME 14 6442
TEST_TIME 15 6608
TEST_TIME 16 6983
TEST_TIME 17 7286
TEST_TIME 18 7227
TEST_TIME 19 7260
TEST_TIME 20 7614
TEST_TIME 21 7978
TEST_TIME 22 7878
TEST_TIME 23 8224
TEST_TIME 24 8165
TEST_TIME 25 8042
TEST_TIME 26 8499
TEST_TIME 27 8908
TEST_TIME 28 9398
TEST_TIME 29 10078
TEST_TIME 30 10710
TEST_TIME 31 11190
TEST_TIME 32 11031
TEST_TIME 33 11145
TEST_TIME 34 11281
TEST_TIME 35 11054
TEST_TIME 36 10833
TEST_TIME 37 10754
TEST_TIME 38 10562
TEST_TIME 39 10460
TEST_TIME 40 10493
TEST_TIME 41 10314
TEST_TIME 42 10229
TEST_TIME 43 10252
TEST_TIME 44 10180
TEST_TIME 45 10180
TEST_TIME 46 10217
TEST_TIME 47 10080
TEST_TIME 48 10034
TEST_TIME 49 9954
TEST_TIME 50 9813
TEST_TIME 51 9817
TEST_TIME 52 9792
TEST_TIME 53 9730
TEST_TIME 54 9675
TEST_TIME 55 9750
TEST_TIME 56 9724
TEST_TIME 57 9742
TEST_TIME 58 9681
TEST_TIME 59 9733
TEST_TIME 60 9745
TEST_TIME 61 9784
TEST_TIME 62 9867
TEST_TIME 63 9864
TEST_TIME 64 9849
TEST_TIME 65 9750
TEST_TIME 66 9730
TEST_TIME 67 9699
TEST_TIME 68 9582
TEST_TIME 69 9505
TEST_TIME 70 9458
TEST_TIME 71 9398
TEST_TIME 72 9353
TEST_TIME 73 9388
TEST_TIME 74 9377
TEST_TIME 75 9374
TEST_TIME 76 9376
TEST_TIME 77 9376
TEST_TIME 78 9418
TEST_TIME 79 9395
TEST_TIME 80 9416
TEST_TIME 81 9470
TEST_TIME 82 9474
TEST_TIME 83 9545
TEST_TIME 84 9480
TEST_TIME 85 9418
TEST_TIME 86 9379
TEST_TIME 87 9401
TEST_TIME 88 9380
TEST_TIME 89 9331
TEST_TIME 90 9261
TEST_TIME 91 9228
TEST_TIME 92 9218
TEST_TIME 93 9194
TEST_TIME 94 9182
TEST_TIME 95 9156
TEST_TIME 96 9148
TEST_TIME 97 9113
TEST_TIME 98 9086
TEST_TIME 99 9062
TEST_TIME 100 9089
TEST_TIME 101 9052
TEST_TIME 102 9137
TEST_TIME 103 9091
TEST_TIME 104 9188
TEST_TIME 105 9298
TEST_TIME 106 9419
TEST_TIME 107 9415
TEST_TIME 108 9367
TEST_TIME 109 9335
TEST_TIME 110 9310
TEST_TIME 111 9248
TEST_TIME 112 9221
TEST_TIME 113 9235
TEST_TIME 114 9208
TEST_TIME 115 9169
TEST_TIME 116 9142
TEST_TIME 117 9164
TEST_TIME 118 9108
TEST_TIME 119 9109
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2023 13:39:12 to 09-01-2023 13:39:15.

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 1493293 1493293 0 0.00 0 25557 13 0 0
doc_list_biud 1493293 1493293 0 0.00 0 51908 44 0 0
sp_add_invoice_to_stock 4893 4767 126 2.57 47 213646 25254 0 126
sp_cancel_adding_invoice 5723 5723 0 0.00 6 77009 1201 0 0
sp_cancel_client_order 1675 1675 0 0.00 6 27398 411 0 0
sp_cancel_customer_reserve 551270 551270 0 0.00 1 51910 105 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 4185 125 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 4 3575 111 0 0
sp_cancel_supplier_invoice 6259 6247 12 0.19 3 18673 923 0 12
sp_cancel_supplier_order 409 409 0 0.00 10 1443627 178732 0 0
sp_cancel_write_off 10882 10882 0 0.00 2 8212 174 0 0
sp_client_order 20059 20059 0 0.00 6 34689 484 0 0
sp_customer_reserve 445451 445451 0 0.00 0 49898 239 0 0
sp_fill_shopping_cart 30523 30523 0 0.00 1 15117 77 0 0
sp_fill_shopping_cart_clo_res 445426 445426 0 0.00 0 14643 19 0 0
sp_get_clo_for_invoice 4716 4716 0 0.00 0 36481 327 0 0
sp_kill_cost_storno 17642 17642 0 0.00 0 21345 71 0 0
sp_kill_qstorno_ret_qs2qd 1120078 1120078 0 0.00 0 51901 45 0 0
sp_kill_qty_storno 590486 590486 0 0.00 0 51907 104 0 0
sp_lock_selected_doc 19871 19871 0 0.00 0 8096 9 0 0
sp_make_cost_storno 26955 26955 0 0.00 0 14501 85 0 0
sp_multiply_rows_for_pdistr 26955 26955 0 0.00 0 7143 37 0 0
sp_multiply_rows_for_qdistr 20059 20059 0 0.00 0 12474 193 0 0
sp_pay_from_customer 6914 6914 0 0.00 5 10620 161 0 0
sp_pay_to_supplier 5761 5761 0 0.00 6 15900 190 0 0
sp_payment_common 12675 12675 0 0.00 2 9674 40 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 0 27389 338 0 0
sp_qd_handle_on_invoice_upd_sts 10436 10436 0 0.00 0 31307 525 0 0
sp_qd_handle_on_reserve_upd_sts 9567 9567 0 0.00 0 22326 73 0 0
sp_reserve_write_off 9567 9567 0 0.00 8 22353 293 0 0
sp_supplier_invoice 5254 5254 0 0.00 10 109756 3196 0 0
sp_supplier_order 5216 5216 0 0.00 7 35223 1309 0 0
srv_aggregate_perf_data 108 107 1 0.92 2452 179094 16503 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 722 111708 35351 0 0
srv_find_qd_qs_mism 1035049 1035049 0 0.00 0 25556 9 0 0
srv_make_invnt_saldo 482 482 0 0.00 64 84761 3744 0 0
srv_make_money_saldo 839 839 0 0.00 1 11706 320 0 0
srv_recalc_idx_stat 5 5 0 0.00 61030 357597 172622 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 4767 4767 0 0.00 1 11331 841 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 14019 525 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 5754 25 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 5444 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 444 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 913 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1262 22 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 80607 6279 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 861 17 0 0
t$perf-norm:sp_client_order 20059 20059 0 0.00 0 8904 35 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 3213 24 0 0
t$perf-norm:sp_pay_from_customer 6914 6914 0 0.00 0 7770 18 0 0
t$perf-norm:sp_pay_to_supplier 5761 5761 0 0.00 0 5623 19 0 0
t$perf-norm:sp_reserve_write_off 9567 9567 0 0.00 0 5159 22 0 0
t$perf-norm:sp_supplier_invoice 5254 5254 0 0.00 0 8841 29 0 0
t$perf-norm:sp_supplier_order 5216 5216 0 0.00 0 2372 25 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 901 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 124 5 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 2264 13 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 3690 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 17 208 93 0 0
v_add_invoice_to_stock 9678 9426 252 2.60 0 6337 29 0 252
v_all_customers 37156 37156 0 0.00 0 240 3 0 0
v_all_suppliers 29742 29742 0 0.00 0 201 4 0 0
v_all_wares 40116 40116 0 0.00 0 201 4 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 792 14 0 0
v_cancel_client_order 3350 3350 0 0.00 0 6063 13 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 3355 20 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 732 9 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 3549 17 0 0
v_cancel_supplier_invoice 836 812 24 2.87 0 959 24 0 24
v_cancel_supplier_order 818 818 0 0.00 0 7740 25 0 0
v_cancel_write_off 1914 1914 0 0.00 0 7558 38 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 651 5 0 0
v_max_id_clo_res 932 932 0 0.00 0 10586 27 0 0
v_max_id_ord_sup 5249 5249 0 0.00 0 1440 5 0 0
v_max_non_paid_invoice 1354 1354 0 0.00 0 3504 9 0 0
v_max_non_paid_realizn 3287 3287 0 0.00 0 4542 9 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 2729 8 0 0
v_min_id_clo_res 932 932 0 0.00 0 10004 31 0 0
v_min_id_ord_sup 5249 5249 0 0.00 0 4658 8 0 0
v_min_non_paid_invoice 5761 5761 0 0.00 0 5679 13 0 0
v_min_non_paid_realizn 6914 6914 0 0.00 0 5595 15 0 0
v_our_firm 10214 10214 0 0.00 0 78 4 0 0
v_reserve_write_off 19134 19134 0 0.00 0 7552 12 0 0
x_lock_depdocs_on_canc_invoice 11564 11564 0 0.00 0 16323 122 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 6888 377 0 0
x_make_qstorno_1000_1200 5216 5216 0 0.00 2 35209 1164 0 0
x_make_qstorno_1200_2000 5254 5254 0 0.00 4 109492 2980 0 0
x_make_qstorno_2100_3300 445451 445451 0 0.00 0 45486 209 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-01-2023 13:39:16 to 09-01-2023 13:39:21.

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
11:07:29 919 8192000000 8928978704 8984506368 28305712 0.344 7 4 9 0 29187088 170544 710832
11:08:31 17174 8192000000 9714543280 10014593024 796030288 8.856 100 85 170 1 824673888 7810976 20832624
11:09:57 21394 8192000000 9780013600 10093719552 851828144 9.418 100 88 176 0 885089904 12485760 20776000
11:11:24 11638 8192000000 9816879216 10139807744 885086016 9.750 100 89 178 0 927030944 21973040 19971888
11:12:44 7025 8192000000 9886076896 10216509440 922928976 10.125 100 89 176 0 990998848 49046928 19022944
11:13:59 3728 8192000000 9916808624 10258563072 949582256 10.387 100 87 169 0 1036267952 68573728 18111968
11:15:06 4200 8192000000 9948633552 10301652992 988968496 10.771 100 91 177 2 1068174384 60731216 18474672
11:16:14 1483 8192000000 10002711280 10360545280 1021093552 11.083 100 90 174 0 1116734640 78275360 17365728
11:17:17 1382 8192000000 10006915888 10377121792 1038596080 11.251 100 94 183 1 1124658144 68091936 17970128
11:18:20 880 8192000000 10036381216 10408239104 1050433952 11.365 100 98 182 1 1156486608 87897488 18155168
11:19:23 994 8192000000 10041194544 10422173696 1062541920 11.481 100 93 174 1 1164977072 84927408 17507744
11:20:25 1050 8192000000 10043136848 10419830784 1067371536 11.527 100 98 191 0 1159210672 73720256 18118880
11:21:27 1441 8192000000 10023516192 10410049536 1072436336 11.575 100 84 166 2 1147917232 58429104 17051792
11:22:34 1165 8192000000 10034556160 10420219904 1083534528 11.681 100 93 182 2 1158794944 56984992 18275424
11:23:36 1246 8192000000 10054611376 10441228288 1092082672 11.762 100 96 187 0 1182042480 71957728 18002080
11:24:38 1232 8192000000 10057341296 10443620352 1092534400 11.767 100 93 177 2 1181645568 70078816 19032352
11:25:40 1029 8192000000 10064169184 10457047040 1094099856 11.782 100 88 169 3 1191139488 79470896 17568736
11:26:42 1845 8192000000 10037444256 10431246336 1098036016 11.819 100 75 138 0 1162174576 50345696 13792864
11:27:45 1588 8192000000 10042056592 10437435392 1097065968 11.810 100 72 132 1 1165253344 55736976 12450400
11:28:47 1070 8192000000 10065378192 10462253056 1101674480 11.854 100 82 147 2 1189832800 72856000 15302320
11:29:49 1044 8192000000 10052447056 10436792320 1097176608 11.811 100 97 188 1 1180860288 63578080 20105600
11:30:52 1025 8192000000 10034246624 10414964736 1084607504 11.691 100 97 183 1 1156559312 54368144 17583664
11:31:53 2425 8192000000 10003163632 10385035264 1063426560 11.489 100 80 152 0 1127414416 49200144 14787712
11:32:56 955 8192000000 9996405856 10375528448 1046840304 11.330 100 84 156 2 1115625296 53151456 15633536
11:33:58 6519 8192000000 10010816080 10396192768 1055634336 11.415 99 65 117 2 1127548608 60644752 11269520
11:35:05 942 8192000000 9967865456 10338516992 1023504448 11.106 100 84 163 2 1087396432 47652576 16239408
11:36:07 1095 8192000000 9972692928 10348654592 1038091792 11.246 100 86 168 3 1096122912 39899440 18131680
11:37:09 1230 8192000000 9987742336 10369196032 1045668288 11.319 100 76 150 1 1114040128 53074496 15297344
11:38:17 2197 8192000000 10009705568 10384842752 1059642704 11.453 100 87 161 0 1128465328 54266272 14556352
11:39:24 1077 8192000000 10034652080 10410086400 1072798976 11.579 100 91 180 2 1151396576 60918656 17678944
11:40:29 6592 8192000000 10033308080 10421854208 1083569696 11.681 100 72 139 0 1153756240 55508048 14678496
11:41:41 24299 8192000000 10136599392 10532106240 1101193616 11.849 100 63 126 0 1242991968 128059776 13738576
11:43:09 4525 8192000000 10115179648 10512257024 1115700608 11.986 100 61 114 2 1233567696 105840512 12026576
11:44:16 14474 8192000000 10217403472 10618023936 1130089056 12.122 100 62 123 2 1319171296 175378832 13703408
11:45:37 2555 8192000000 10188114016 10602721280 1146831216 12.280 100 55 108 0 1302404016 143905920 11666880
11:46:45 1632 8192000000 10277735264 10675093504 1158346224 12.388 100 92 176 1 1398357984 221815376 18196384
11:47:51 700 8192000000 10296613040 10691715072 1171597984 12.512 100 96 189 2 1408807392 215676272 21533136
11:48:58 2628 8192000000 10357501136 10760433664 1174450752 12.538 100 79 155 0 1470554256 278330304 17773200
11:50:03 1416 8192000000 10370979296 10779373568 1174897888 12.543 100 94 187 0 1477092880 281107936 21087056
11:51:10 1749 8192000000 10390028400 10797096960 1188343200 12.668 100 86 164 1 1499581504 292951648 18286656
11:52:15 1153 8192000000 10439436288 10860498944 1192976640 12.711 100 97 190 1 1546903248 331695536 22231072
11:53:19 3444 8192000000 10443150384 10908852224 1193896784 12.720 100 80 158 1 1552247568 338219392 20131392
11:54:25 869 8192000000 10492659136 10900037632 1210692048 12.876 100 96 186 0 1600805280 368272912 21840320
11:55:28 874 8192000000 10533325648 10940841984 1190183968 12.685 100 94 182 1 1640147488 427598736 22364784
11:56:33 764 8192000000 10564581888 11004596224 1207713616 12.848 100 97 186 1 1672685920 441420752 23551552
11:57:36 953 8192000000 10572660032 11040854016 1206606448 12.838 100 86 169 0 1673670736 446698528 20365760
11:58:43 887 8192000000 10565917360 11001581568 1195334368 12.733 100 96 185 1 1673811904 454677232 23800304
11:59:47 838 8192000000 10615145152 11036704768 1205205616 12.825 100 89 178 0 1719151392 491904688 22041088
12:00:50 5186 8192000000 10605415920 11034296320 1196819248 12.747 100 69 135 1 1718868848 504978096 17071504
12:01:59 2230 8192000000 10666260176 11090284544 1191960256 12.702 100 63 124 1 1771602928 564597072 15045600
12:03:11 11853 8192000000 10619440400 11094712320 1183080848 12.619 100 46 92 0 1727914128 532436304 12396976
12:05:06 12184 8192000000 10527881664 11007549440 1135468176 12.173 99 43 84 0 1606300208 460421408 10410624
12:06:19 26958 8192000000 10318280928 10801299456 1135534304 12.174 100 21 42 1 1475845664 334489232 5822128
12:07:47 40307 8192000000 10147624064 10625949696 1128844368 12.110 99 22 42 1 1245117792 111641360 4632064
12:09:39 49215 8192000000 10081680320 10561134592 1105249584 11.887 96 20 41 0 1172745648 63720928 3775136
12:11:47 81864 8192000000 10076310192 10515677184 1123131616 12.057 98 11 22 0 1171945968 46117568 2696784
12:14:10 111708 8192000000 10113035328 10558840832 1124093376 12.066 98 35 70 0 1201634640 70450576 7090688
12:17:05 91536 8192000000 10106499312 10565365760 1132027536 12.140 98 20 39 0 1195948080 60334880 3585664
12:19:52 33204 8192000000 10140523136 10594516992 1139027632 12.206 99 40 80 0 1234720560 87427072 8265856
12:21:28 6491 8192000000 10094162944 10523549696 1112625472 11.957 98 59 114 0 1185382112 59991888 12764752
12:22:36 42747 8192000000 10066318416 10564894720 1119723872 12.024 100 14 28 0 1198550608 74987344 3839392
12:24:20 24849 8192000000 10051245072 10494611456 1097759680 11.816 98 11 20 0 1137785520 37766976 2258864
12:25:46 48005 8192000000 10085492016 10549977088 1118643680 12.014 99 28 54 0 1204475312 79663376 6168256
12:27:34 106573 8192000000 10038216432 10519736320 1078571136 11.634 96 17 34 0 1135262784 53180544 3511104
12:30:22 60945 8192000000 10074558064 10525065216 1120892336 12.035 100 23 46 0 1187261920 60849488 5520096
12:32:28 34801 8192000000 10092918608 10545348608 1113658992 11.967 98 18 36 0 1169864992 52309888 3896112
12:34:06 31593 8192000000 10111810736 10574221312 1110005904 11.932 98 22 42 0 1174621968 59623840 4992224
12:35:38 16659 8192000000 10107012304 10561556480 1130001152 12.121 100 42 76 1 1231392848 92718912 8672784
12:36:58 42962 8192000000 10129052704 10583875584 1115748720 11.987 98 34 66 0 1217486880 94595136 7143024
12:38:46 49031 8192000000 10073622480 10524545024 1097122032 11.810 97 42 82 1 1193362352 87750384 8489936
12:40:36 9911 8192000000 10109591456 10537472000 1136227968 12.180 100 56 112 0 1204929664 57393632 11308064
12:41:47 101136 8192000000 10110336176 10560602112 1134675152 12.165 100 36 72 0 1247310144 105082400 7552592
12:44:33 59470 8192000000 10048579840 10522308608 1113436704 11.965 98 8 16 0 1150590032 35642848 1510480
12:46:32 54113 8192000000 10044561488 10508668928 1090648848 11.749 96 19 38 1 1121116112 26934848 3532416
12:48:28 51078 8192000000 10099753984 10539483136 1113103840 11.962 98 17 34 0 1173864016 57074304 3685872
12:50:25 66002 8192000000 10064745776 10533814272 1111561680 11.947 98 17 35 0 1168757088 53256080 3939328
12:52:33 37077 8192000000 10098751904 10526253056 1104216304 11.878 98 39 78 0 1176642752 64198896 8227552
12:54:16 88240 8192000000 10100375200 10600189952 1113063680 11.961 98 24 48 0 1184174000 66314640 4795680
12:56:46 43131 8192000000 10075132608 10517491712 1085163568 11.697 96 31 58 0 1128372272 37373040 5835664
12:58:53 34460 8192000000 10126111072 10557104128 1125276288 12.077 99 31 62 0 1202514816 71161488 6077040
13:00:29 66052 8192000000 10076556352 10541506560 1101150768 11.849 97 17 32 0 1156116944 51700208 3265968
13:02:59 72940 8192000000 10113032976 10570747904 1131702640 12.137 100 38 68 0 1211921536 70848256 9370640
13:05:12 50038 8192000000 10062779024 10517749760 1116322496 11.992 98 18 36 0 1151738720 32245136 3171088
13:07:02 87238 8192000000 10081707504 10550497280 1110845680 11.940 97 15 31 0 1163020976 48825904 3349392
13:09:49 54417 8192000000 10063743904 10507382784 1110775696 11.940 97 12 20 1 1145407392 32874656 1757040
13:11:43 25309 8192000000 10080453232 10521047040 1111005584 11.942 97 24 48 0 1173945888 58131424 4808880
13:13:14 39504 8192000000 10145490912 10581917696 1129468480 12.116 98 39 78 0 1220506784 83113104 7925200
13:14:54 18065 8192000000 10207100864 10639876096 1143387424 12.247 98 42 83 0 1300030336 147589360 9053552
13:16:14 15148 8192000000 10261602336 10705027072 1158760768 12.392 99 48 94 1 1344745696 174960896 11024032
13:17:30 34753 8192000000 10262482944 10714607616 1131170032 12.132 97 30 57 0 1327667792 189401808 7095952
13:19:06 16858 8192000000 10274219008 10737319936 1145766048 12.270 98 49 95 0 1357089472 200975792 10347632
13:20:27 33961 8192000000 10291194144 10732482560 1148595840 12.296 99 21 42 0 1370463088 216786256 5080992
13:22:12 33250 8192000000 10213882992 10722000896 1138066512 12.197 99 23 45 0 1340186880 196863360 5257008
13:23:57 88167 8192000000 10082910400 10567393280 1121347856 12.040 98 26 52 0 1177148640 50340192 5460592
13:26:27 81197 8192000000 10087295776 10572795904 1138174672 12.198 99 16 32 1 1180281952 39132032 2975248
13:30:05 90848 8192000000 10095615024 10595667968 1145834480 12.270 100 10 20 0 1191971200 44215248 1921472
13:32:37 77658 8192000000 10119621072 10575687680 1141029936 12.225 99 25 50 0 1190572912 44574192 4968784
13:35:06 55123 8192000000 10073262272 10518507520 1118018000 12.008 97 36 68 0 1159684016 34596720 7069296
13:37:02 19907 8192000000 10089825360 10529492992 1135351088 12.172 99 61 113 1 1179271008 31464784 12455136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-01-2023 13:39:23 to 09-01-2023 13:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2023 13:39:40 to 09-01-2023 13:39:42.

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          217569
MON$OLDEST_ACTIVE               217570
MON$OLDEST_SNAPSHOT             217570
MON$NEXT_TRANSACTION            217570
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                       2062944
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

09.01.23 13:39:43. 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-01-09 13:39:46 No severe PSQL-related problems occured
09.01.23 13:39:47. End of report.