Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.05.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 16390028 kB
MemAvailable 31446928 kB
Buffers 0 kB
Cached 15349008 kB
SwapCached 2588 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-05-22 07:37:31
DTS_END                         2022-05-22 07:37:15.8950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45392
TRN_ID                          218564
ATT_ID                          259
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: 9063.60 22305 1087632
customer order: creation 167.18 461 20061
customer order: refuse 14.01 397 1681
order to supplier: creation 44.94 1342 5393
order to supplier: removal 3.35 165636 402
invoice (draft): creation 45.13 3279 5416
invoice (draft): removal 51.25 942 6150
invoice accept: apply 40.97 26040 4916
invoice accept: cancel 46.04 1096 5525
customer reserve: creation 3836.99 244 460439
customer reserve: removal 4529.18 96 543502
realization accept: apply 81.09 279 9731
realization accept: cancel 77.84 173 9341
payment from customer: creation 57.30 175 6876
payment from customer: removal 5.04 129 605
payment to supplier: creation 47.51 198 5701
payment to supplier: removal 3.44 122 413
service: total inventory turnovers 3.93 4098 472
service: total monetary turnovers 7.37 327 884
service: aggregate perf. data 1.00 14726 120
service: refresh index statistics 0.03 226349 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-05-2022 07:39:03 to 22-05-2022 07:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9325
TEST_TIME 2 8390
TEST_TIME 3 7406
TEST_TIME 4 7434
TEST_TIME 5 7023
TEST_TIME 6 7249
TEST_TIME 7 7289
TEST_TIME 8 7343
TEST_TIME 9 7180
TEST_TIME 10 7074
TEST_TIME 11 7301
TEST_TIME 12 7248
TEST_TIME 13 7128
TEST_TIME 14 7272
TEST_TIME 15 7188
TEST_TIME 16 7100
TEST_TIME 17 7204
TEST_TIME 18 7373
TEST_TIME 19 7587
TEST_TIME 20 7926
TEST_TIME 21 8468
TEST_TIME 22 8713
TEST_TIME 23 8879
TEST_TIME 24 8956
TEST_TIME 25 9069
TEST_TIME 26 9359
TEST_TIME 27 9343
TEST_TIME 28 9617
TEST_TIME 29 9560
TEST_TIME 30 9953
TEST_TIME 31 10614
TEST_TIME 32 10995
TEST_TIME 33 11522
TEST_TIME 34 11277
TEST_TIME 35 11055
TEST_TIME 36 10854
TEST_TIME 37 10893
TEST_TIME 38 10761
TEST_TIME 39 10587
TEST_TIME 40 10464
TEST_TIME 41 10462
TEST_TIME 42 10415
TEST_TIME 43 10343
TEST_TIME 44 10293
TEST_TIME 45 10111
TEST_TIME 46 9941
TEST_TIME 47 9859
TEST_TIME 48 9869
TEST_TIME 49 9746
TEST_TIME 50 9670
TEST_TIME 51 9590
TEST_TIME 52 9558
TEST_TIME 53 9545
TEST_TIME 54 9543
TEST_TIME 55 9527
TEST_TIME 56 9439
TEST_TIME 57 9306
TEST_TIME 58 9304
TEST_TIME 59 9244
TEST_TIME 60 9139
TEST_TIME 61 9141
TEST_TIME 62 9267
TEST_TIME 63 9294
TEST_TIME 64 9276
TEST_TIME 65 9271
TEST_TIME 66 9234
TEST_TIME 67 9260
TEST_TIME 68 9209
TEST_TIME 69 9159
TEST_TIME 70 9209
TEST_TIME 71 9291
TEST_TIME 72 9495
TEST_TIME 73 9557
TEST_TIME 74 9476
TEST_TIME 75 9372
TEST_TIME 76 9376
TEST_TIME 77 9334
TEST_TIME 78 9352
TEST_TIME 79 9344
TEST_TIME 80 9281
TEST_TIME 81 9286
TEST_TIME 82 9261
TEST_TIME 83 9217
TEST_TIME 84 9157
TEST_TIME 85 9095
TEST_TIME 86 9075
TEST_TIME 87 9037
TEST_TIME 88 9055
TEST_TIME 89 9013
TEST_TIME 90 9024
TEST_TIME 91 8985
TEST_TIME 92 9034
TEST_TIME 93 9055
TEST_TIME 94 9054
TEST_TIME 95 9073
TEST_TIME 96 9083
TEST_TIME 97 9097
TEST_TIME 98 9163
TEST_TIME 99 9181
TEST_TIME 100 9199
TEST_TIME 101 9159
TEST_TIME 102 9120
TEST_TIME 103 9098
TEST_TIME 104 9123
TEST_TIME 105 9084
TEST_TIME 106 9027
TEST_TIME 107 9007
TEST_TIME 108 8998
TEST_TIME 109 9006
TEST_TIME 110 8976
TEST_TIME 111 8957
TEST_TIME 112 8924
TEST_TIME 113 8897
TEST_TIME 114 8921
TEST_TIME 115 8918
TEST_TIME 116 8948
TEST_TIME 117 9025
TEST_TIME 118 9086
TEST_TIME 119 9133
TEST_TIME 120 9089

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-05-2022 07:39:07 to 22-05-2022 07:39:10.

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 1510483 1510483 0 0.00 0 16817 13 0 0
doc_list_biud 1510475 1510475 0 0.00 0 82819 40 0 0
sp_add_invoice_to_stock 5000 4916 84 1.68 51 192787 26041 0 84
sp_cancel_adding_invoice 5525 5525 0 0.00 5 71266 1097 0 0
sp_cancel_client_order 1681 1681 0 0.00 5 17987 398 0 0
sp_cancel_customer_reserve 543502 543502 0 0.00 1 59772 97 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 4 8649 129 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 2953 123 0 0
sp_cancel_supplier_invoice 6163 6150 13 0.21 3 82826 942 0 13
sp_cancel_supplier_order 402 402 0 0.00 9 1384559 165637 0 0
sp_cancel_write_off 9341 9341 0 0.00 2 8887 173 0 0
sp_client_order 20061 20061 0 0.00 5 39560 461 0 0
sp_customer_reserve 460439 460439 0 0.00 1 112811 245 0 0
sp_fill_shopping_cart 30854 30854 0 0.00 1 24237 89 0 0
sp_fill_shopping_cart_clo_res 460408 460408 0 0.00 0 14353 19 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 30202 358 0 0
sp_kill_cost_storno 15884 15884 0 0.00 0 9049 74 0 0
sp_kill_qstorno_ret_qs2qd 1102897 1102897 0 0.00 0 82818 42 0 0
sp_kill_qty_storno 581209 581209 0 0.00 0 82819 97 0 0
sp_lock_selected_doc 20101 20101 0 0.00 0 9652 9 0 0
sp_make_cost_storno 27186 27186 0 0.00 0 12945 80 0 0
sp_multiply_rows_for_pdistr 27185 27185 0 0.00 0 8636 37 0 0
sp_multiply_rows_for_qdistr 20061 20061 0 0.00 0 12433 177 0 0
sp_pay_from_customer 6876 6876 0 0.00 6 11288 175 0 0
sp_pay_to_supplier 5701 5701 0 0.00 6 11283 198 0 0
sp_payment_common 12576 12576 0 0.00 2 11187 43 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 1 17978 326 0 0
sp_qd_handle_on_invoice_upd_sts 10402 10402 0 0.00 0 16963 493 0 0
sp_qd_handle_on_reserve_upd_sts 9731 9731 0 0.00 0 11367 66 0 0
sp_reserve_write_off 9731 9731 0 0.00 8 16865 280 0 0
sp_supplier_invoice 5416 5416 0 0.00 13 94872 3280 0 0
sp_supplier_order 5393 5393 0 0.00 7 49954 1342 0 0
srv_aggregate_perf_data 121 120 1 0.82 2596 226082 14726 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 711 275553 39908 0 0
srv_find_qd_qs_mism 1039061 1039061 0 0.00 0 10491 10 0 0
srv_make_invnt_saldo 472 472 0 0.00 47 119300 4099 0 0
srv_make_money_saldo 884 884 0 0.00 1 8391 327 0 0
srv_recalc_idx_stat 4 4 0 0.00 55287 379524 226349 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 4923 4923 0 0.00 1 24130 928 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 1 6556 403 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 2292 21 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2677 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 2213 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1549 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 4123 54 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 98507 6823 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 5307 24 0 0
t$perf-norm:sp_client_order 20061 20061 0 0.00 0 27176 34 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 2342 14 0 0
t$perf-norm:sp_pay_from_customer 6876 6876 0 0.00 0 4472 19 0 0
t$perf-norm:sp_pay_to_supplier 5701 5701 0 0.00 0 4256 18 0 0
t$perf-norm:sp_reserve_write_off 9731 9731 0 0.00 0 31418 27 0 0
t$perf-norm:sp_supplier_invoice 5416 5416 0 0.00 0 24314 36 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 3151 29 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 514 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 196 7 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 956 13 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 985 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 184 71 0 0
v_add_invoice_to_stock 9922 9754 168 1.69 0 6632 24 0 168
v_all_customers 37042 37042 0 0.00 0 87 3 0 0
v_all_suppliers 30184 30184 0 0.00 0 81 4 0 0
v_all_wares 40122 40122 0 0.00 0 74 4 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 257 12 0 0
v_cancel_client_order 3362 3362 0 0.00 0 1789 9 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 3419 15 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3028 17 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2821 16 0 0
v_cancel_supplier_invoice 838 812 26 3.10 0 5520 37 0 26
v_cancel_supplier_order 804 804 0 0.00 0 341 9 0 0
v_cancel_write_off 1904 1904 0 0.00 0 6676 32 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 2438 5 0 0
v_max_id_clo_res 957 957 0 0.00 0 665 8 0 0
v_max_id_ord_sup 5401 5401 0 0.00 0 7794 8 0 0
v_max_non_paid_invoice 1402 1402 0 0.00 0 955 8 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 4160 9 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 2381 8 0 0
v_min_id_clo_res 957 957 0 0.00 0 1681 9 0 0
v_min_id_ord_sup 5401 5401 0 0.00 0 8879 14 0 0
v_min_non_paid_invoice 5701 5701 0 0.00 0 11173 17 0 0
v_min_non_paid_realizn 6875 6875 0 0.00 0 6873 16 0 0
v_our_firm 9956 9956 0 0.00 0 82 4 0 0
v_reserve_write_off 19462 19462 0 0.00 0 9458 13 0 0
x_lock_depdocs_on_canc_invoice 11269 11269 0 0.00 0 11002 116 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 13805 397 0 0
x_make_qstorno_1000_1200 5393 5393 0 0.00 3 49935 1178 0 0
x_make_qstorno_1200_2000 5416 5416 0 0.00 6 94861 3016 0 0
x_make_qstorno_2100_3300 460436 460436 0 0.00 0 112810 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-05-2022 07:39:11 to 22-05-2022 07:39:15.

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:19 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 18330 8192000000 9713218800 10009071616 791330240 8.808 100 88 177 0 820829568 7441792 22057536
05:09:44 30080 8192000000 9761270448 10066903040 833864224 9.238 100 91 183 0 868121520 12371040 21886256
05:11:19 14684 8192000000 9798884448 10116558848 866880528 9.569 100 88 175 1 907539200 19813344 20845328
05:12:44 8650 8192000000 9875189520 10209681408 933253072 10.227 100 86 170 0 983251168 31434944 18563152
05:13:54 7613 8192000000 9919281344 10262462464 961746768 10.506 100 85 169 0 1037174960 57250512 18177680
05:15:05 2416 8192000000 9966658816 10320109568 999811600 10.877 100 95 185 1 1081630272 62575920 19242752
05:16:10 1275 8192000000 10016849232 10379157504 1020145968 11.073 100 91 178 1 1136729552 98565520 18018064
05:17:14 763 8192000000 10017736592 10385416192 1040807232 11.272 100 99 191 3 1140822976 79885488 20130256
05:18:16 1308 8192000000 10037528464 10413395968 1054286800 11.402 100 93 185 0 1159851760 86978064 18586896
05:19:18 1366 8192000000 10040000832 10419486720 1069314768 11.546 100 97 190 1 1159711264 71030448 19366048
05:20:21 1074 8192000000 10039729680 10416840704 1075072736 11.600 100 98 194 0 1163123808 68524976 19526096
05:21:24 1044 8192000000 10071076064 10457116672 1083600352 11.682 100 91 174 1 1193283808 92353824 17329632
05:22:25 9325 8192000000 10049727504 10437271552 1088257232 11.726 100 81 161 0 1168465264 64358224 15849808
05:23:35 935 8192000000 10043300688 10436718592 1097930432 11.818 100 83 161 2 1167682960 53650944 16101584
05:24:37 1277 8192000000 10061278464 10451025920 1104192384 11.877 100 97 181 1 1185691600 63250448 18248768
05:25:40 1225 8192000000 10081386864 10475372544 1109711600 11.930 100 99 188 0 1203956080 75660624 18583856
05:26:42 1358 8192000000 10080831792 10470531072 1112717376 11.958 100 97 186 2 1201484448 69549536 19217536
05:27:44 1308 8192000000 10097914000 10487390208 1110642688 11.939 100 98 189 1 1219715808 90409648 18663472
05:28:46 1231 8192000000 10059435600 10448564224 1093180816 11.773 100 94 181 2 1183610720 71059360 19370544
05:29:48 1233 8192000000 10052633200 10440314880 1100866528 11.846 100 90 171 2 1175981360 56147872 18966960
05:30:51 1309 8192000000 10036800048 10421207040 1081633648 11.663 100 97 180 1 1154003792 52372144 19998000
05:31:53 1328 8192000000 10038073072 10417889280 1068793008 11.541 100 96 181 2 1161693200 74171552 18728640
05:32:55 1226 8192000000 10004457552 10379608064 1050403216 11.365 100 96 180 0 1126448032 57797968 18246848
05:33:57 4973 8192000000 9998377504 10380054528 1051668096 11.377 100 62 117 1 1119544816 55983360 11893360
05:35:02 1090 8192000000 10007814688 10389065728 1055778816 11.416 100 91 175 5 1129717088 55735152 18203120
05:36:04 1212 8192000000 9976974368 10360090624 1037961856 11.245 100 76 145 3 1102638256 49514736 15161664
05:37:07 939 8192000000 9997947408 10376581120 1053851232 11.398 100 85 151 0 1117513008 47622512 16039264
05:38:11 10931 8192000000 10048172720 10428645376 1075818960 11.608 100 82 156 0 1161726528 69888896 16018672
05:39:28 2451 8192000000 10062608048 10451292160 1086850960 11.713 100 78 140 3 1185161728 84356320 13954448
05:40:38 11659 8192000000 10066113008 10455400448 1099886208 11.837 100 70 135 1 1181795568 67348224 14561136
05:41:52 8082 8192000000 10106249360 10501967872 1115718640 11.987 100 64 124 0 1220164352 91949376 12496336
05:43:06 11151 8192000000 10133944720 10536935424 1120155616 12.028 99 70 133 0 1243962880 108596960 15210304
05:44:19 1010 8192000000 10193806384 10593308672 1144174608 12.255 100 94 184 1 1305385328 140381472 20829248
05:45:25 1125 8192000000 10222998416 10621235200 1159705936 12.401 100 90 174 0 1334577536 156982832 17888768
05:46:29 1784 8192000000 10242679616 10642341888 1168308768 12.481 100 90 179 0 1361201296 173116624 19775904
05:47:35 1942 8192000000 10281550480 10677661696 1168347776 12.481 100 89 176 2 1386847312 199398464 19101072
05:48:40 2145 8192000000 10306187264 10719596544 1180541024 12.595 100 79 151 0 1413456288 216258128 16657136
05:49:46 3659 8192000000 10319474256 10726002688 1189283168 12.677 100 79 152 1 1424306864 218439344 16584352
05:50:56 1437 8192000000 10389504736 10795270144 1193359632 12.715 100 91 182 0 1502820752 288433792 21027328
05:52:02 1201 8192000000 10401143072 10811682816 1195144640 12.731 100 96 187 0 1507791040 291526000 21120400
05:53:08 2464 8192000000 10429454080 10835353600 1192510640 12.707 100 87 173 0 1538369824 326346832 19512352
05:54:14 1259 8192000000 10474427840 10888458240 1200567344 12.782 100 99 193 0 1581830224 359215680 22047200
05:55:17 1001 8192000000 10511586960 10936311808 1196577456 12.745 100 91 179 3 1615581280 397979136 21024688
05:56:20 1125 8192000000 10531974880 10978783232 1193590864 12.717 100 97 190 2 1641412336 422987104 24834368
05:57:24 706 8192000000 10496411040 10953830400 1184571728 12.633 99 90 178 2 1606503392 400727200 21204464
05:58:29 774 8192000000 10463304336 10950291456 1194154576 12.722 100 95 184 1 1571673584 353293248 24225760
05:59:31 2758 8192000000 10427538032 10870112256 1189694016 12.681 100 52 102 1 1536295392 333567104 13034272
06:00:40 1376 8192000000 10454598192 10903744512 1209166272 12.861 100 87 162 2 1560276816 332300624 18809920
06:01:45 2802 8192000000 10479892608 10921492480 1203587728 12.810 100 71 142 1 1590975472 370290512 17097232
06:02:58 11777 8192000000 10490481792 10969063424 1202571664 12.800 100 54 108 0 1584934832 368258448 14104720
06:04:12 19275 8192000000 10507429472 10973638656 1188071904 12.665 100 40 79 1 1614402640 416465744 9864992
06:05:34 33084 8192000000 10456450240 10988732416 1160931024 12.412 99 25 50 1 1567794256 400295472 6567760
06:07:17 25032 8192000000 10399512336 10909638656 1157227296 12.377 100 29 56 2 1526985216 362640544 7117376
06:08:56 28938 8192000000 10183150544 10647994368 1142793184 12.242 99 28 53 0 1285336032 136957904 5584944
06:10:28 63121 8192000000 10072572176 10503933952 1105639472 11.891 97 10 20 1 1135280896 27736048 1905376
06:12:54 67979 8192000000 10135281424 10576543744 1142183712 12.236 100 26 50 1 1225756928 78027216 5546000
06:15:07 34536 8192000000 10071026240 10501218304 1109454784 11.927 98 34 70 2 1136846496 19542096 7849616
06:16:47 34139 8192000000 10123085792 10566447104 1134171712 12.161 100 66 116 2 1195198320 48170992 12855616
06:19:06 68692 8192000000 10096184208 10539323392 1132171568 12.142 99 22 44 0 1211932160 75193312 4567280
06:21:18 136030 8192000000 10058507120 10486198272 1094712640 11.787 96 33 66 2 1129998688 27991584 7294464
06:24:43 92087 8192000000 10063998224 10528186368 1106569504 11.900 98 24 48 1 1170024480 57531424 5923552
06:27:20 1049 8192000000 10089508496 10506387456 1129342576 12.115 100 81 152 1 1186564224 39930736 17290912
06:28:22 49883 8192000000 10108011184 10541572096 1113098688 11.962 99 35 70 1 1199771824 78302208 8370928
06:30:15 275553 8192000000 10045144688 10503385088 1060003296 11.457 94 23 46 0 1159487024 94203376 5280352
06:36:07 66554 8192000000 10053404752 10515611648 1107309280 11.907 99 27 52 1 1136049168 23753280 4986608
06:38:13 90054 8192000000 10081703344 10544926720 1090984496 11.752 96 17 34 1 1147164560 52382144 3797920
06:40:44 42830 8192000000 10078367408 10527268864 1126365440 12.087 100 12 24 0 1158259792 29379056 2515296
06:42:28 85258 8192000000 10123744416 10573283328 1138712784 12.203 99 28 54 1 1209340624 64228944 6398896
06:44:57 62621 8192000000 10171650672 10608570368 1123657824 12.062 97 24 48 1 1216317264 86829008 5830432
06:47:59 116139 8192000000 10135306864 10668732416 1122265536 12.048 97 10 20 0 1215380144 90727456 2387152
06:50:56 43542 8192000000 10092019808 10522025984 1136935200 12.187 99 13 26 0 1178868624 39309936 2623488
06:53:17 83043 8192000000 10144527712 10591866880 1132099088 12.141 99 36 72 1 1218565120 78981776 7484256
06:55:45 2747 8192000000 10105398880 10607112192 1143662912 12.250 100 48 90 0 1215479152 61757840 10058400
06:56:49 34036 8192000000 10076211040 10521251840 1143968784 12.253 100 27 52 1 1166280480 17005072 5306624
06:58:26 46751 8192000000 10068189808 10532126720 1115492032 11.984 97 20 40 0 1173080336 53286352 4301952
07:00:19 58038 8192000000 10051781328 10499854336 1094873344 11.789 97 8 16 0 1111507504 14318432 2315728
07:02:18 81121 8192000000 10079656304 10566156288 1132544512 12.145 100 26 51 1 1214358336 76144880 5668944
07:04:40 26006 8192000000 10071460448 10517327872 1122801632 12.053 99 43 86 1 1180132288 48885152 8445504
07:06:08 78068 8192000000 10069525072 10555699200 1130622352 12.127 99 21 38 1 1166955376 32105584 4227440
07:08:31 42225 8192000000 10117530672 10559819776 1111944304 11.951 97 26 52 0 1191474464 73809456 5720704
07:10:21 33039 8192000000 10095932912 10547208192 1136590656 12.183 100 34 67 1 1179986864 36915728 6480480
07:11:55 45998 8192000000 10090235856 10542514176 1092179920 11.763 97 39 78 1 1180223232 80208624 7834688
07:13:43 41746 8192000000 10140895376 10574356480 1116899520 11.998 99 49 94 0 1216772992 89859408 10014064
07:15:25 26225 8192000000 10060584656 10519891968 1097100256 11.810 98 21 42 1 1169551744 68264496 4186992
07:16:55 69287 8192000000 10062816528 10511839232 1084861040 11.694 97 31 62 1 1174448288 83485936 6101312
07:19:08 83285 8192000000 10112737600 10591641600 1095627248 11.796 99 34 68 0 1210077024 107545008 6904768
07:24:19 60828 8192000000 10058788816 10487312384 1084144432 11.687 97 18 36 0 1133304064 44987200 4172432
07:26:41 34052 8192000000 10077501344 10519207936 1127823296 12.101 100 29 55 0 1188997184 55355472 5818416
07:28:27 49910 8192000000 10139710016 10576867328 1136034816 12.178 100 21 42 1 1228044416 87406816 4602784
07:30:19 22260 8192000000 10205824864 10635743232 1143785520 12.251 100 50 98 0 1299806528 145578816 10442192
07:31:45 53934 8192000000 10202447200 10638852096 1114967392 11.979 97 33 66 0 1288440416 165531488 7941536
07:33:39 42286 8192000000 10235436976 10677030912 1147899488 12.290 100 33 66 1 1319543456 164188736 7455232
07:35:22 97834 8192000000 10093748896 10581688320 1125382816 12.078 98 13 26 2 1252295440 123302704 3609920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-05-2022 07:39:17 to 22-05-2022 07:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 84 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-05-2022 07:39:34 to 22-05-2022 07:39:36.

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          223342
MON$OLDEST_ACTIVE               223343
MON$OLDEST_SNAPSHOT             223343
MON$NEXT_TRANSACTION            223343
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                       2047968
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

22.05.22 07:39:37. 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-05-22 07:39:39 No severe PSQL-related problems occured
22.05.22 07:39:41. End of report.