Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2022 04:37
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 15378800 kB
MemAvailable 30456988 kB
Buffers 20 kB
Cached 16253428 kB
SwapCached 2076 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 2022-10-12 04:37:22
DTS_END                         2022-10-12 04:37:22.3420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45664
TRN_ID                          211904
ATT_ID                          621
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: 8704.90 29231 1044588
customer order: creation 156.20 515 18744
customer order: refuse 13.53 363 1623
order to supplier: creation 41.96 1320 5035
order to supplier: removal 3.41 165968 409
invoice (draft): creation 42.27 2898 5072
invoice (draft): removal 50.46 939 6055
invoice accept: apply 38.38 27614 4606
invoice accept: cancel 45.53 1039 5464
customer reserve: creation 3587.14 265 430457
customer reserve: removal 4450.38 100 534046
realization accept: apply 76.97 259 9236
realization accept: cancel 80.78 165 9694
payment from customer: creation 53.31 190 6397
payment from customer: removal 5.32 112 638
payment to supplier: creation 44.25 204 5310
payment to supplier: removal 3.28 96 394
service: total inventory turnovers 3.95 3644 474
service: total monetary turnovers 6.88 360 825
service: aggregate perf. data 0.88 15171 105
service: refresh index statistics 0.03 363389 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2022 04:38:14 to 12-10-2022 04:38:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9614
TEST_TIME 2 7227
TEST_TIME 3 6886
TEST_TIME 4 7039
TEST_TIME 5 6902
TEST_TIME 6 6898
TEST_TIME 7 6849
TEST_TIME 8 6745
TEST_TIME 9 6629
TEST_TIME 10 6849
TEST_TIME 11 6805
TEST_TIME 12 7387
TEST_TIME 13 7471
TEST_TIME 14 7423
TEST_TIME 15 7608
TEST_TIME 16 7704
TEST_TIME 17 7595
TEST_TIME 18 7499
TEST_TIME 19 7763
TEST_TIME 20 7701
TEST_TIME 21 8052
TEST_TIME 22 7974
TEST_TIME 23 7883
TEST_TIME 24 8170
TEST_TIME 25 8211
TEST_TIME 26 8669
TEST_TIME 27 8983
TEST_TIME 28 9767
TEST_TIME 29 9582
TEST_TIME 30 10114
TEST_TIME 31 10924
TEST_TIME 32 11210
TEST_TIME 33 11021
TEST_TIME 34 10889
TEST_TIME 35 10679
TEST_TIME 36 10635
TEST_TIME 37 10541
TEST_TIME 38 10442
TEST_TIME 39 10226
TEST_TIME 40 10079
TEST_TIME 41 9969
TEST_TIME 42 9991
TEST_TIME 43 9941
TEST_TIME 44 9955
TEST_TIME 45 9859
TEST_TIME 46 9814
TEST_TIME 47 9682
TEST_TIME 48 9593
TEST_TIME 49 9485
TEST_TIME 50 9333
TEST_TIME 51 9317
TEST_TIME 52 9280
TEST_TIME 53 9229
TEST_TIME 54 9239
TEST_TIME 55 9240
TEST_TIME 56 9156
TEST_TIME 57 9155
TEST_TIME 58 9194
TEST_TIME 59 9182
TEST_TIME 60 9135
TEST_TIME 61 9256
TEST_TIME 62 9246
TEST_TIME 63 9299
TEST_TIME 64 9254
TEST_TIME 65 9233
TEST_TIME 66 9303
TEST_TIME 67 9187
TEST_TIME 68 9086
TEST_TIME 69 9042
TEST_TIME 70 9007
TEST_TIME 71 8937
TEST_TIME 72 8896
TEST_TIME 73 8907
TEST_TIME 74 8890
TEST_TIME 75 8869
TEST_TIME 76 8924
TEST_TIME 77 8901
TEST_TIME 78 8922
TEST_TIME 79 8870
TEST_TIME 80 8860
TEST_TIME 81 8864
TEST_TIME 82 8925
TEST_TIME 83 9081
TEST_TIME 84 9091
TEST_TIME 85 9113
TEST_TIME 86 9085
TEST_TIME 87 9077
TEST_TIME 88 9002
TEST_TIME 89 8987
TEST_TIME 90 8974
TEST_TIME 91 8998
TEST_TIME 92 8954
TEST_TIME 93 8917
TEST_TIME 94 8923
TEST_TIME 95 8920
TEST_TIME 96 8894
TEST_TIME 97 8895
TEST_TIME 98 8876
TEST_TIME 99 8873
TEST_TIME 100 8869
TEST_TIME 101 8876
TEST_TIME 102 8891
TEST_TIME 103 8866
TEST_TIME 104 8862
TEST_TIME 105 8951
TEST_TIME 106 8976
TEST_TIME 107 8957
TEST_TIME 108 8960
TEST_TIME 109 8950
TEST_TIME 110 8933
TEST_TIME 111 8859
TEST_TIME 112 8880
TEST_TIME 113 8846
TEST_TIME 114 8828
TEST_TIME 115 8805
TEST_TIME 116 8780
TEST_TIME 117 8797
TEST_TIME 118 8787
TEST_TIME 119 8790
TEST_TIME 120 8732

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-10-2022 04:38:17 to 12-10-2022 04:38:21.

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 1441080 1441080 0 0.00 0 16023 13 0 0
doc_list_biud 1441072 1441072 0 0.00 0 38975 42 0 0
sp_add_invoice_to_stock 4717 4606 111 2.35 76 295270 27615 0 111
sp_cancel_adding_invoice 5464 5464 0 0.00 7 104800 1039 0 0
sp_cancel_client_order 1623 1623 0 0.00 5 8831 364 0 0
sp_cancel_customer_reserve 534046 534046 0 0.00 1 35680 100 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 4163 112 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 1289 96 0 0
sp_cancel_supplier_invoice 6068 6055 13 0.21 3 38982 940 0 13
sp_cancel_supplier_order 409 409 0 0.00 17 1462911 165969 0 0
sp_cancel_write_off 9694 9694 0 0.00 2 16785 165 0 0
sp_client_order 18744 18744 0 0.00 5 40939 516 0 0
sp_customer_reserve 430457 430457 0 0.00 0 60109 265 0 0
sp_fill_shopping_cart 28837 28837 0 0.00 1 20344 74 0 0
sp_fill_shopping_cart_clo_res 430421 430421 0 0.00 0 22857 22 0 0
sp_get_clo_for_invoice 4563 4563 0 0.00 0 27391 314 0 0
sp_kill_cost_storno 16190 16190 0 0.00 0 8517 74 0 0
sp_kill_qstorno_ret_qs2qd 1084250 1084250 0 0.00 0 38974 44 0 0
sp_kill_qty_storno 571088 571088 0 0.00 0 38975 99 0 0
sp_lock_selected_doc 19212 19212 0 0.00 0 7715 6 0 0
sp_make_cost_storno 25506 25506 0 0.00 0 14864 74 0 0
sp_multiply_rows_for_pdistr 25505 25505 0 0.00 0 10991 38 0 0
sp_multiply_rows_for_qdistr 18741 18741 0 0.00 0 17103 199 0 0
sp_pay_from_customer 6397 6397 0 0.00 6 34669 190 0 0
sp_pay_to_supplier 5310 5310 0 0.00 5 28174 204 0 0
sp_payment_common 11707 11707 0 0.00 2 6180 44 0 0
sp_qd_handle_on_cancel_clo 1623 1623 0 0.00 0 6595 293 0 0
sp_qd_handle_on_invoice_upd_sts 10025 10025 0 0.00 0 18962 470 0 0
sp_qd_handle_on_reserve_upd_sts 9236 9236 0 0.00 0 7855 51 0 0
sp_reserve_write_off 9236 9236 0 0.00 9 39531 260 0 0
sp_supplier_invoice 5072 5072 0 0.00 9 86508 2899 0 0
sp_supplier_order 5035 5035 0 0.00 7 65015 1321 0 0
srv_aggregate_perf_data 106 105 1 0.94 1918 235432 15171 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 786 135989 37136 0 0
srv_find_qd_qs_mism 999726 999726 0 0.00 0 16018 10 0 0
srv_make_invnt_saldo 474 474 0 0.00 43 49102 3644 0 0
srv_make_money_saldo 825 825 0 0.00 1 21578 361 0 0
srv_recalc_idx_stat 4 4 0 0.00 223363 438899 363390 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 4613 4613 0 0.00 1 12718 837 0 0
t$perf-norm:sp_cancel_adding_invoice 340 340 0 0.00 1 6322 396 0 0
t$perf-norm:sp_cancel_client_order 1623 1623 0 0.00 0 3686 23 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 708 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 1400 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 2259 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 10039 57 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 112543 6326 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 4485 20 0 0
t$perf-norm:sp_client_order 18746 18746 0 0.00 0 6212 35 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 1854 15 0 0
t$perf-norm:sp_pay_from_customer 6397 6397 0 0.00 0 8138 19 0 0
t$perf-norm:sp_pay_to_supplier 5310 5310 0 0.00 0 11509 24 0 0
t$perf-norm:sp_reserve_write_off 9236 9236 0 0.00 0 26798 24 0 0
t$perf-norm:sp_supplier_invoice 5072 5072 0 0.00 0 4765 28 0 0
t$perf-norm:sp_supplier_order 5035 5035 0 0.00 0 7186 32 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 910 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 110 4 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 815 10 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1828 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 160 63 0 0
v_add_invoice_to_stock 9336 9114 222 2.37 0 1952 22 0 222
v_all_customers 34306 34306 0 0.00 0 92 3 0 0
v_all_suppliers 28288 28288 0 0.00 0 63 4 0 0
v_all_wares 37474 37474 0 0.00 0 87 3 0 0
v_cancel_adding_invoice 680 680 0 0.00 0 5753 19 0 0
v_cancel_client_order 3246 3246 0 0.00 0 2204 10 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 2021 12 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1828 11 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 285 9 0 0
v_cancel_supplier_invoice 810 784 26 3.20 0 702 22 0 26
v_cancel_supplier_order 818 818 0 0.00 0 419 9 0 0
v_cancel_write_off 1884 1884 0 0.00 0 478 21 0 0
v_max_id_clo_ord 5032 5032 0 0.00 0 980 5 0 0
v_max_id_clo_res 960 960 0 0.00 0 1591 11 0 0
v_max_id_ord_sup 5065 5065 0 0.00 0 2923 6 0 0
v_max_non_paid_invoice 1261 1261 0 0.00 0 72 7 0 0
v_max_non_paid_realizn 3356 3356 0 0.00 0 5158 11 0 0
v_min_id_clo_ord 5032 5032 0 0.00 0 3647 7 0 0
v_min_id_clo_res 960 960 0 0.00 0 960 9 0 0
v_min_id_ord_sup 5064 5064 0 0.00 0 2884 9 0 0
v_min_non_paid_invoice 5310 5310 0 0.00 0 5404 15 0 0
v_min_non_paid_realizn 6397 6397 0 0.00 0 5167 19 0 0
v_our_firm 9248 9248 0 0.00 0 94 4 0 0
v_reserve_write_off 18472 18472 0 0.00 0 39513 16 0 0
x_lock_depdocs_on_canc_invoice 11127 11127 0 0.00 0 10144 115 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 18037 388 0 0
x_make_qstorno_1000_1200 5035 5035 0 0.00 3 64999 1169 0 0
x_make_qstorno_1200_2000 5072 5072 0 0.00 4 86490 2695 0 0
x_make_qstorno_2100_3300 430456 430456 0 0.00 0 51061 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2022 04:38:22 to 12-10-2022 04:38:26.

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:26 3 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 3708 8192000000 9708282496 10001608704 792275184 8.818 100 87 174 0 820706208 7387088 21043936
02:09:36 23206 8192000000 9745176576 10050457600 813861104 9.037 100 87 174 0 850734032 16117808 20755120
02:11:10 11833 8192000000 9782175824 10098872320 854698112 9.447 100 86 168 0 892507744 18328144 19481488
02:12:27 11980 8192000000 9853392832 10182225920 902301792 9.921 100 79 158 0 964182080 44447360 17432928
02:13:42 5384 8192000000 9908829472 10246807552 935332672 10.247 100 83 158 0 1025861488 73511968 17016848
02:14:50 2647 8192000000 9941324272 10295926784 985060816 10.733 100 85 161 0 1061450176 59981904 16407456
02:15:56 1138 8192000000 9963533216 10325856256 1022125760 11.093 100 85 167 1 1085097200 46213008 16758432
02:16:59 1525 8192000000 10012437216 10383261696 1043214944 11.296 100 86 162 0 1133883008 74064608 16603456
02:18:02 1193 8192000000 10031244096 10405085184 1056056016 11.419 100 96 185 0 1152106704 77806768 18243920
02:19:05 963 8192000000 10012726384 10391027712 1063134752 11.486 100 95 183 0 1137466736 55481968 18850016
02:20:10 1447 8192000000 10043870384 10424143872 1072361424 11.575 100 91 173 2 1169479152 79178288 17939440
02:21:14 1075 8192000000 10057612800 10436677632 1077593168 11.625 100 95 191 2 1176971632 81151696 18226768
02:22:16 2038 8192000000 10034938432 10413817856 1083167248 11.678 100 82 147 1 1160334848 62809040 14358560
02:23:19 1117 8192000000 10054090320 10437967872 1089499840 11.738 100 95 178 1 1178127040 70666560 17960640
02:24:22 1146 8192000000 10055374976 10439483392 1087192976 11.716 100 97 184 2 1174791360 70062256 17536128
02:25:24 1496 8192000000 10054272720 10443546624 1091398272 11.756 100 92 173 1 1179618464 68223104 19997088
02:26:27 1073 8192000000 10046065600 10430337024 1092333104 11.765 100 96 182 0 1166463584 55794704 18335776
02:27:28 1554 8192000000 10059219600 10457354240 1093522432 11.776 100 76 150 1 1184473200 75872048 15078720
02:28:33 854 8192000000 10032156000 10419920896 1081245904 11.659 100 82 150 1 1153963248 56345904 16371440
02:29:35 1834 8192000000 10016728032 10411155456 1076624176 11.615 100 68 137 1 1143394784 52328208 14442400
02:30:38 1322 8192000000 10068007952 10459414528 1079673392 11.644 100 93 175 4 1194788064 96298560 18816112
02:31:40 1116 8192000000 10002348496 10381570048 1041416864 11.278 99 86 160 1 1128699136 70295184 16987088
02:32:42 1417 8192000000 10031697136 10415435776 1072103344 11.572 100 97 172 1 1155531312 66202768 17225200
02:33:44 1199 8192000000 10015446160 10387755008 1041654128 11.281 100 97 188 4 1137470064 76587136 19228800
02:34:46 1023 8192000000 9989464944 10357657600 1024690048 11.117 99 95 175 0 1108272272 65729664 17852560
02:35:49 1129 8192000000 9994865408 10371043328 1041090560 11.275 100 88 164 3 1115102624 58679392 15332672
02:36:50 3032 8192000000 9981184656 10359160832 1042723200 11.291 100 59 112 5 1101300080 47652416 10924464
02:37:58 1157 8192000000 9995606880 10368696320 1042262432 11.286 99 89 167 1 1112826656 54507152 16057072
02:39:05 784 8192000000 10041044544 10426404864 1073093072 11.582 100 89 163 2 1163819280 74309520 16416688
02:40:11 18845 8192000000 10051764864 10447405056 1082810848 11.674 100 72 137 2 1178837360 81460624 14565888
02:41:34 3560 8192000000 10090409344 10475970560 1085993696 11.705 99 80 152 1 1200854640 98891984 15968960
02:42:42 3912 8192000000 10107104544 10497441792 1116446080 11.993 100 60 118 0 1232459856 103097632 12916144
02:43:53 2159 8192000000 10149196976 10550345728 1122867552 12.054 100 70 139 0 1265165120 127339408 14958160
02:45:02 2862 8192000000 10185800720 10590531584 1141317008 12.228 100 60 118 2 1301983296 148059152 12607136
02:46:11 1888 8192000000 10238833824 10632495104 1162113888 12.423 100 91 167 1 1350870832 171014912 17742032
02:47:15 1232 8192000000 10282837264 10680430592 1169131056 12.489 100 87 159 4 1390242848 203066928 18044864
02:48:21 1204 8192000000 10327110672 10725400576 1179780512 12.588 100 89 165 1 1432020656 234427264 17812880
02:49:25 2284 8192000000 10332267136 10755469312 1191195824 12.694 100 84 165 1 1446745552 236948368 18601360
02:50:30 1160 8192000000 10374764288 10779820032 1193325264 12.714 100 95 183 0 1478827904 264609040 20893600
02:51:34 933 8192000000 10414185968 10819411968 1197744192 12.755 100 97 186 2 1522569888 304059104 20766592
02:52:38 2483 8192000000 10443281968 10861428736 1212759136 12.895 100 91 178 0 1555942800 322249280 20934384
02:53:44 2727 8192000000 10442564768 10857889792 1200737632 12.783 99 92 178 0 1547052784 324158288 22156864
02:54:50 1790 8192000000 10520200432 10930520064 1222554608 12.985 100 98 191 0 1626990960 380840752 23595600
02:55:55 2461 8192000000 10527598320 10973732864 1209472848 12.864 99 87 170 1 1634535808 403851520 21211440
02:57:00 1160 8192000000 10571946848 10979692544 1216845360 12.932 100 94 188 0 1678056512 439084816 22126336
02:58:07 3663 8192000000 10583434640 11021381632 1211795424 12.886 100 75 150 0 1688753952 458024672 18933856
02:59:17 5897 8192000000 10593651440 11018956800 1209448368 12.864 100 61 122 0 1693151248 468434096 15268784
03:00:32 6694 8192000000 10603830480 11063021568 1209451024 12.864 100 51 102 0 1730876256 508199152 13226080
03:01:45 8016 8192000000 10591029296 11052752896 1182616896 12.615 100 55 103 0 1701234048 505333264 13283888
03:02:58 5847 8192000000 10608212544 11069747200 1178362960 12.575 100 58 113 2 1712869808 519679200 14827648
03:04:26 5534 8192000000 10472580992 11001372672 1181541104 12.605 100 43 83 1 1579694192 387396384 10756704
03:05:37 14588 8192000000 10401175728 10920779776 1183797312 12.626 100 31 58 0 1505447968 313985312 7665344
03:06:55 23841 8192000000 10363750768 10886606848 1171851488 12.514 100 30 60 0 1508392592 328268000 8273104
03:08:25 24894 8192000000 10186924960 10722410496 1128614496 12.108 99 19 36 0 1301884368 168634272 4635600
03:09:50 38337 8192000000 10085360464 10528161792 1093772880 11.779 97 33 64 0 1166341024 65399280 7168864
03:11:29 45471 8192000000 10122685728 10555834368 1125288656 12.077 100 50 97 0 1209178800 73543792 10346352
03:13:20 67885 8192000000 10083516208 10536976384 1108788576 11.921 98 35 67 2 1162213200 46119056 7305568
03:15:38 71638 8192000000 10114410288 10563739648 1140724944 12.222 100 15 30 0 1214579232 70806192 3048096
03:17:53 67910 8192000000 10165856272 10632404992 1105147760 11.886 96 19 38 1 1238198544 128564560 4486224
03:20:01 26547 8192000000 10164315904 10611150848 1136867504 12.186 100 21 37 2 1257769616 117209008 3693104
03:21:31 133765 8192000000 10076103344 10586992640 1128057168 12.103 99 12 24 1 1202428144 71953296 2417680
03:25:01 32636 8192000000 10052407392 10502787072 1110769408 11.940 98 20 40 0 1154051264 39096560 4185296
03:27:20 60467 8192000000 10058705984 10518384640 1117233536 12.001 99 29 56 2 1163174384 39762416 6178432
03:29:27 70547 8192000000 10094040928 10572996608 1127843168 12.101 99 11 22 0 1174441248 44049984 2548096
03:31:51 70978 8192000000 10082482640 10556616704 1128175264 12.104 99 12 24 1 1162563280 32246544 2141472
03:34:02 64491 8192000000 10126505184 10574508032 1123270224 12.058 98 31 55 0 1204175360 74672432 6232704
03:36:08 41911 8192000000 10100420704 10535264256 1114852912 11.978 97 33 65 0 1161557312 40037520 6666880
03:37:57 45835 8192000000 10149709984 10656833536 1149215600 12.302 100 46 88 0 1265112432 105729552 10167280
03:39:54 49774 8192000000 10081703120 10533191680 1117633456 12.005 98 14 28 0 1157127200 36724368 2769376
03:41:44 14085 8192000000 10135487520 10561179648 1127818896 12.101 99 50 96 1 1195465568 57183424 10463248
03:43:09 54746 8192000000 10079822832 10521321472 1126622304 12.090 100 34 67 1 1178682912 45679168 6381440
03:45:03 100768 8192000000 10079343088 10515369984 1125367760 12.078 99 42 84 0 1171435312 37757840 8309712
03:47:45 64052 8192000000 10073473648 10561122304 1141017200 12.225 100 11 22 0 1187635360 44658416 1959744
03:49:49 81816 8192000000 10077747648 10552573952 1111885232 11.950 97 8 13 0 1142121792 28849392 1387168
03:52:12 80946 8192000000 10092947216 10571575296 1136048144 12.178 99 24 46 0 1194465296 53533360 4883792
03:54:34 20460 8192000000 10094433104 10531442688 1140416592 12.219 100 34 65 1 1185187120 37854304 6916224
03:55:55 33359 8192000000 10139709744 10580815872 1132963536 12.149 99 29 58 0 1218333280 78748560 6621184
03:57:33 36700 8192000000 10173424880 10639101952 1125751088 12.081 98 28 55 1 1254778640 122721216 6306336
03:59:10 77945 8192000000 10169468320 10667913216 1107177824 11.906 97 22 44 0 1269885744 157335408 5372512
04:01:31 49586 8192000000 10079759040 10535698432 1080352944 11.651 96 33 66 0 1139962400 52398688 7210768
04:03:24 13290 8192000000 10099751440 10527985664 1133852048 12.158 100 44 89 0 1186390640 44146736 8391856
04:04:41 135988 8192000000 10122655648 10590511104 1122557344 12.051 99 35 70 0 1249222960 119298384 7367232
04:08:02 67592 8192000000 10100582880 10592681984 1118654336 12.014 99 17 34 0 1193459040 71206784 3597920
04:10:14 42727 8192000000 10081618208 10563502080 1131448688 12.135 100 11 21 0 1201407088 67422832 2535568
04:12:58 64858 8192000000 10048490176 10512019456 1107423136 11.908 98 16 32 0 1161217472 50530416 3263920
04:15:06 74200 8192000000 10089712352 10538115072 1099397504 11.832 97 15 30 0 1148419648 45831040 3191104
04:17:20 56936 8192000000 10064966256 10528600064 1107664784 11.910 98 15 30 0 1161910832 51383376 2862672
04:19:18 44077 8192000000 10114157312 10571931648 1111304832 11.945 97 32 56 0 1182790608 65505776 5980000
04:21:04 34169 8192000000 10149442912 10606198784 1123450064 12.060 99 30 60 0 1228937216 99054752 6432400
04:22:40 40291 8192000000 10108095824 10546556928 1115014960 11.980 99 41 78 0 1180829424 58040240 7774224
04:24:22 41512 8192000000 10068085680 10490302464 1086532912 11.710 97 45 90 1 1136003792 40738928 8731952
04:26:09 44110 8192000000 10082926768 10514915328 1105306784 11.888 99 42 84 0 1161146672 48198752 7641136
04:27:57 89243 8192000000 10123350864 10611212288 1124776640 12.072 100 39 78 0 1233813024 101121520 7914864
04:30:54 70515 8192000000 10052572352 10541797376 1102866528 11.865 99 9 18 1 1170787824 65915744 2005552
04:34:41 74043 8192000000 10075897168 10523144192 1097603072 11.815 97 20 38 0 1127134720 25779744 3751904
04:37:10 14317 8192000000 9058072592 9483804672 114058496 1.373 11 9 16 1 119478016 3965168 1454352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-10-2022 04:38:28 to 12-10-2022 04:38:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 111 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 12-10-2022 04:38:45 to 12-10-2022 04:38:47.

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          212216
MON$OLDEST_ACTIVE               212217
MON$OLDEST_SNAPSHOT             212217
MON$NEXT_TRANSACTION            212217
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                       2037792
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

12.10.22 04:38:48. 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-10-12 04:38:51 No severe PSQL-related problems occured
12.10.22 04:38:52. End of report.