Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.01.2023 10:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 13770144 kB
MemAvailable 30427668 kB
Buffers 0 kB
Cached 17941388 kB
SwapCached 1588 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-25 10:37:26
DTS_END                         2023-01-25 10:37:26.9540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53864
TRN_ID                          225424
ATT_ID                          607
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: 9131.88 30496 1095826
customer order: creation 175.37 493 21044
customer order: refuse 14.54 482 1745
order to supplier: creation 48.43 1348 5812
order to supplier: removal 3.36 179904 403
invoice (draft): creation 45.98 3354 5518
invoice (draft): removal 48.78 1123 5854
invoice accept: apply 43.97 26232 5276
invoice accept: cancel 44.27 1331 5312
customer reserve: creation 4056.87 245 486824
customer reserve: removal 4351.23 109 522147
realization accept: apply 86.53 321 10384
realization accept: cancel 78.34 187 9401
payment from customer: creation 61.32 191 7358
payment from customer: removal 6.28 135 754
payment to supplier: creation 49.94 209 5993
payment to supplier: removal 3.56 115 427
service: total inventory turnovers 3.72 5298 446
service: total monetary turnovers 8.36 410 1003
service: aggregate perf. data 1.01 14754 121
service: refresh index statistics 0.03 373670 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-01-2023 10:40:11 to 25-01-2023 10:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10841
TEST_TIME 2 7917
TEST_TIME 3 6405
TEST_TIME 4 7354
TEST_TIME 5 6945
TEST_TIME 6 6721
TEST_TIME 7 6539
TEST_TIME 8 6578
TEST_TIME 9 6969
TEST_TIME 10 7081
TEST_TIME 11 6986
TEST_TIME 12 7320
TEST_TIME 13 7204
TEST_TIME 14 7240
TEST_TIME 15 7255
TEST_TIME 16 7229
TEST_TIME 17 7501
TEST_TIME 18 7612
TEST_TIME 19 7694
TEST_TIME 20 7962
TEST_TIME 21 8029
TEST_TIME 22 8013
TEST_TIME 23 8121
TEST_TIME 24 8354
TEST_TIME 25 8405
TEST_TIME 26 8850
TEST_TIME 27 8904
TEST_TIME 28 9212
TEST_TIME 29 9554
TEST_TIME 30 9641
TEST_TIME 31 9558
TEST_TIME 32 9665
TEST_TIME 33 9760
TEST_TIME 34 9711
TEST_TIME 35 9718
TEST_TIME 36 10122
TEST_TIME 37 10370
TEST_TIME 38 10286
TEST_TIME 39 10613
TEST_TIME 40 10718
TEST_TIME 41 10811
TEST_TIME 42 10718
TEST_TIME 43 10850
TEST_TIME 44 10756
TEST_TIME 45 10932
TEST_TIME 46 10827
TEST_TIME 47 10630
TEST_TIME 48 10530
TEST_TIME 49 10430
TEST_TIME 50 10414
TEST_TIME 51 10423
TEST_TIME 52 10465
TEST_TIME 53 10336
TEST_TIME 54 10206
TEST_TIME 55 10104
TEST_TIME 56 10084
TEST_TIME 57 10117
TEST_TIME 58 10101
TEST_TIME 59 10038
TEST_TIME 60 10088
TEST_TIME 61 10075
TEST_TIME 62 10014
TEST_TIME 63 10026
TEST_TIME 64 9988
TEST_TIME 65 9905
TEST_TIME 66 9919
TEST_TIME 67 9877
TEST_TIME 68 9820
TEST_TIME 69 9751
TEST_TIME 70 9736
TEST_TIME 71 9652
TEST_TIME 72 9618
TEST_TIME 73 9555
TEST_TIME 74 9522
TEST_TIME 75 9485
TEST_TIME 76 9520
TEST_TIME 77 9547
TEST_TIME 78 9580
TEST_TIME 79 9560
TEST_TIME 80 9622
TEST_TIME 81 9644
TEST_TIME 82 9671
TEST_TIME 83 9655
TEST_TIME 84 9710
TEST_TIME 85 9675
TEST_TIME 86 9670
TEST_TIME 87 9692
TEST_TIME 88 9708
TEST_TIME 89 9702
TEST_TIME 90 9618
TEST_TIME 91 9566
TEST_TIME 92 9515
TEST_TIME 93 9472
TEST_TIME 94 9452
TEST_TIME 95 9437
TEST_TIME 96 9389
TEST_TIME 97 9360
TEST_TIME 98 9323
TEST_TIME 99 9309
TEST_TIME 100 9278
TEST_TIME 101 9252
TEST_TIME 102 9247
TEST_TIME 103 9210
TEST_TIME 104 9244
TEST_TIME 105 9229
TEST_TIME 106 9252
TEST_TIME 107 9323
TEST_TIME 108 9377
TEST_TIME 109 9496
TEST_TIME 110 9453
TEST_TIME 111 9419
TEST_TIME 112 9455
TEST_TIME 113 9423
TEST_TIME 114 9385
TEST_TIME 115 9359
TEST_TIME 116 9327
TEST_TIME 117 9275
TEST_TIME 118 9247
TEST_TIME 119 9221
TEST_TIME 120 9157

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-01-2023 10:40:15 to 25-01-2023 10:40:18.

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 1541732 1541732 0 0.00 0 24422 14 0 0
doc_list_biud 1541721 1541721 0 0.00 0 75934 44 0 0
sp_add_invoice_to_stock 5439 5276 163 2.99 55 161951 26233 0 163
sp_cancel_adding_invoice 5312 5312 0 0.00 6 69706 1331 0 0
sp_cancel_client_order 1745 1745 0 0.00 5 11280 482 0 0
sp_cancel_customer_reserve 522147 522147 0 0.00 1 29678 109 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 4 1048 136 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 4 3839 116 0 0
sp_cancel_supplier_invoice 5866 5854 12 0.20 3 75969 1124 0 12
sp_cancel_supplier_order 403 403 0 0.00 14 1540823 179905 0 0
sp_cancel_write_off 9401 9401 0 0.00 2 8423 187 0 0
sp_client_order 21044 21044 0 0.00 6 26718 493 0 0
sp_customer_reserve 486824 486824 0 0.00 1 42186 245 0 0
sp_fill_shopping_cart 32366 32366 0 0.00 1 15089 93 0 0
sp_fill_shopping_cart_clo_res 486792 486792 0 0.00 0 18007 16 0 0
sp_get_clo_for_invoice 5228 5228 0 0.00 0 35482 264 0 0
sp_kill_cost_storno 15894 15894 0 0.00 0 6619 77 0 0
sp_kill_qstorno_ret_qs2qd 1059952 1059952 0 0.00 0 75932 47 0 0
sp_kill_qty_storno 560473 560473 0 0.00 0 75933 110 0 0
sp_lock_selected_doc 21508 21508 0 0.00 0 7861 7 0 0
sp_make_cost_storno 28962 28962 0 0.00 0 24414 89 0 0
sp_multiply_rows_for_pdistr 28961 28961 0 0.00 0 9997 42 0 0
sp_multiply_rows_for_qdistr 21044 21044 0 0.00 0 11359 161 0 0
sp_pay_from_customer 7358 7358 0 0.00 6 13291 191 0 0
sp_pay_to_supplier 5993 5993 0 0.00 6 10857 210 0 0
sp_payment_common 13350 13350 0 0.00 2 7874 52 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 1 11273 393 0 0
sp_qd_handle_on_invoice_upd_sts 10539 10539 0 0.00 0 11547 619 0 0
sp_qd_handle_on_reserve_upd_sts 10384 10384 0 0.00 0 10729 68 0 0
sp_reserve_write_off 10384 10384 0 0.00 9 24433 322 0 0
sp_supplier_invoice 5518 5518 0 0.00 10 100702 3354 0 0
sp_supplier_order 5812 5812 0 0.00 7 23412 1348 0 0
srv_aggregate_perf_data 121 121 0 0.00 2316 135542 14754 0 0
srv_fill_mon_cache_memory 77 77 0 0.00 773 141319 28994 0 0
srv_find_qd_qs_mism 1043847 1043847 0 0.00 0 8622 10 0 0
srv_make_invnt_saldo 446 446 0 0.00 67 150484 5298 0 0
srv_make_money_saldo 1003 1003 0 0.00 1 11698 410 0 0
srv_recalc_idx_stat 4 4 0 0.00 189124 482071 373670 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 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-norm:sp_add_invoice_to_stock 5279 5279 0 0.00 0 10245 1166 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 2 7894 555 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 3764 34 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1558 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 760 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 399 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 3422 49 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 115431 8212 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 813 20 0 0
t$perf-norm:sp_client_order 21044 21044 0 0.00 0 8500 43 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 2012 19 0 0
t$perf-norm:sp_pay_from_customer 7358 7358 0 0.00 0 5418 21 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 3733 22 0 0
t$perf-norm:sp_reserve_write_off 10384 10384 0 0.00 0 4469 26 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 4955 37 0 0
t$perf-norm:sp_supplier_order 5814 5814 0 0.00 0 6243 40 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 555 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 77 77 0 0.00 0 451 9 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1222 22 0 0
t$perf-norm:srv_make_money_saldo 1003 1003 0 0.00 0 1156 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 153 45 0 0
v_add_invoice_to_stock 10770 10444 326 3.02 0 6286 31 0 326
v_all_customers 38452 38452 0 0.00 0 223 5 0 0
v_all_suppliers 31252 31252 0 0.00 0 225 5 0 0
v_all_wares 42086 42086 0 0.00 0 87 5 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 745 18 0 0
v_cancel_client_order 3490 3490 0 0.00 0 3408 14 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 919 13 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 4355 16 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 584 12 0 0
v_cancel_supplier_invoice 842 818 24 2.85 0 3297 36 0 24
v_cancel_supplier_order 806 806 0 0.00 0 450 12 0 0
v_cancel_write_off 2058 2058 0 0.00 0 2882 30 0 0
v_max_id_clo_ord 5808 5808 0 0.00 0 1102 6 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 1866 11 0 0
v_max_id_ord_sup 5514 5514 0 0.00 0 4957 9 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 537 10 0 0
v_max_non_paid_realizn 3891 3891 0 0.00 0 1699 8 0 0
v_min_id_clo_ord 5808 5808 0 0.00 0 3824 9 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 4144 14 0 0
v_min_id_ord_sup 5514 5514 0 0.00 0 3996 10 0 0
v_min_non_paid_invoice 5992 5992 0 0.00 0 5012 15 0 0
v_min_non_paid_realizn 7358 7358 0 0.00 0 7864 20 0 0
v_our_firm 10568 10568 0 0.00 0 55 6 0 0
v_reserve_write_off 20767 20767 0 0.00 0 5227 13 0 0
x_lock_depdocs_on_canc_invoice 10757 10757 0 0.00 0 18423 123 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 6795 398 0 0
x_make_qstorno_1000_1200 5812 5812 0 0.00 2 23402 1138 0 0
x_make_qstorno_1200_2000 5518 5518 0 0.00 5 100681 3127 0 0
x_make_qstorno_2100_3300 486821 486821 0 0.00 0 42184 213 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-01-2023 10:40:19 to 25-01-2023 10:40:24.

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
08:07:30 915 8192000000 8925206736 8982245376 21056512 0.256 5 3 7 0 21441328 126768 258048
08:08:33 24309 8192000000 9737283744 10041221120 817768928 9.076 100 88 175 0 846956240 8248416 20938896
08:10:00 25656 8192000000 9779180960 10094333952 856155472 9.462 100 91 183 0 890832016 13414112 21262432
08:11:32 19327 8192000000 9813482208 10134691840 879013168 9.690 100 82 164 0 921755120 23703424 19038528
08:12:53 10234 8192000000 9858078864 10190237696 908960688 9.987 100 85 169 0 969780528 42180864 18638976
08:14:09 3907 8192000000 9912849792 10255503360 946509152 10.357 100 85 163 1 1029263008 65063280 17690576
08:15:16 2303 8192000000 9946766352 10297901056 973697728 10.623 100 81 158 2 1063287776 73349312 16240736
08:16:21 921 8192000000 9987711600 10341892096 1011017168 10.985 100 92 177 0 1101161504 72547808 17596528
08:17:25 1178 8192000000 10025165968 10389893120 1033816208 11.205 100 96 185 3 1147952672 95914992 18221472
08:18:28 1204 8192000000 10032409504 10406969344 1052755488 11.387 100 93 178 0 1153271792 83181792 17334512
08:19:30 1023 8192000000 10015103152 10390941696 1060021328 11.457 100 91 171 0 1135911104 58792448 17097328
08:20:32 5669 8192000000 10065313808 10443395072 1069267104 11.545 100 89 172 3 1194862000 108881328 16713568
08:21:40 1209 8192000000 10068234320 10449920000 1076481136 11.614 100 95 188 1 1192309280 96332736 19495408
08:22:43 1115 8192000000 10046496752 10432315392 1082870720 11.675 100 93 173 2 1173962160 73124704 17966736
08:23:47 1061 8192000000 10069228976 10457391104 1086022464 11.705 100 91 178 2 1187295312 84241456 17031392
08:24:50 1263 8192000000 10051642368 10440736768 1091214480 11.754 100 91 175 0 1177057248 66509136 19333632
08:25:52 1128 8192000000 10054737600 10444218368 1095291424 11.793 100 90 174 1 1176513632 63909792 17312416
08:26:54 5930 8192000000 10036909472 10435067904 1093576144 11.777 100 65 123 2 1160625360 54395344 12653872
08:28:01 1171 8192000000 10061332336 10454573056 1095412432 11.794 100 89 171 4 1185440432 71343360 18684640
08:29:03 2025 8192000000 10050417216 10443563008 1087339840 11.717 100 81 151 0 1167032704 65043296 14649568
08:30:06 2717 8192000000 10029188080 10414813184 1077182032 11.621 99 72 138 1 1152244768 60912688 14150048
08:31:10 3920 8192000000 10051812512 10450972672 1084939600 11.695 100 56 110 0 1167992464 72650288 10402576
08:32:17 1033 8192000000 10027488912 10410926080 1066969088 11.523 100 84 156 2 1148972880 66898080 15105712
08:33:19 2198 8192000000 9951905936 10331344896 1027535488 11.145 98 80 139 2 1077628560 36992080 13100992
08:34:22 990 8192000000 10029535680 10412027904 1060521536 11.461 100 85 159 2 1147315376 69709984 17083856
08:35:24 1631 8192000000 9943361456 10318852096 1022791552 11.099 100 73 142 2 1075540256 38168320 14580384
08:36:27 2177 8192000000 10002040624 10384732160 1043735232 11.301 100 79 156 1 1123159504 63136128 16288144
08:37:36 12097 8192000000 9994115120 10376413184 1044006528 11.303 100 67 132 1 1115914256 58513152 13394576
08:39:00 7661 8192000000 10010819312 10398666752 1057648400 11.434 100 62 122 0 1132208080 62879776 11679904
08:40:24 9970 8192000000 10031463376 10420629504 1068912784 11.542 100 70 133 0 1148331344 66247616 13170944
08:41:37 1433 8192000000 10079132992 10459594752 1089564176 11.739 100 92 178 1 1199095120 90828896 18702048
08:42:42 9744 8192000000 10110274464 10495131648 1102367872 11.860 100 71 130 0 1230127216 114194752 13564592
08:43:57 6516 8192000000 10152635072 10544992256 1111643248 11.948 100 67 128 1 1265064480 139863024 13558208
08:45:07 7611 8192000000 10178041504 10572587008 1126822496 12.091 100 77 148 2 1302075376 159432864 15820016
08:46:20 890 8192000000 10196303152 10605535232 1134655424 12.165 100 97 175 1 1311805552 157084368 20065760
08:47:26 1847 8192000000 10223547440 10622316544 1141325360 12.228 100 93 171 1 1341121744 180414768 19381616
08:48:32 1126 8192000000 10271121664 10678190080 1163690032 12.438 100 88 169 1 1375508000 193929520 17888448
08:49:37 1232 8192000000 10279624128 10686513152 1161717248 12.419 99 90 175 2 1380233760 199619920 18896592
08:50:42 1481 8192000000 10293575264 10689499136 1174469584 12.539 100 90 171 2 1403431392 210700736 18261072
08:51:49 802 8192000000 10331364112 10729566208 1177071632 12.563 100 98 186 0 1436799712 239427856 20300224
08:52:52 2334 8192000000 10365173856 10769399808 1184341856 12.631 100 82 156 0 1471761072 270010224 17408992
08:53:57 805 8192000000 10401501168 10828914688 1194910672 12.729 100 93 179 0 1511065968 295144432 21010864
08:55:02 763 8192000000 10418006048 10836385792 1202210640 12.797 100 98 194 1 1526601472 302794080 21596752
08:56:07 1602 8192000000 10405755840 10847760384 1200808656 12.784 100 97 188 0 1518347216 294870240 22668320
08:57:12 1639 8192000000 10441500704 10880131072 1195611712 12.736 100 97 188 0 1545345680 326324976 23408992
08:58:17 1308 8192000000 10438497456 10858553344 1183606304 12.624 100 83 153 3 1547886352 346224912 18055136
08:59:25 766 8192000000 10434261216 10854424576 1168724016 12.485 99 96 187 2 1542319248 350709440 22885792
09:00:29 857 8192000000 10457310240 10879942656 1168129328 12.479 100 80 143 2 1564349776 379449872 16770576
09:01:36 1034 8192000000 10424989776 10881142784 1158650080 12.391 100 97 184 0 1529834112 350512032 20672000
09:02:41 1631 8192000000 10411588240 10895265792 1153273552 12.340 100 72 146 1 1519635024 348371632 17989840
09:03:47 1248 8192000000 10385761312 10834464768 1154834096 12.355 100 71 139 2 1490628160 318428064 17366000
09:05:02 4546 8192000000 10377261424 10834948096 1158758432 12.392 100 64 126 1 1506918896 333071616 15088848
09:06:09 2758 8192000000 10317281072 10775896064 1158672256 12.391 100 75 147 1 1422942480 246398848 17871376
09:07:15 7725 8192000000 10312679808 10755600384 1153455856 12.342 100 63 123 3 1419966048 252592592 13917600
09:08:33 986 8192000000 10308029744 10735206400 1151759856 12.326 99 93 180 0 1405802864 232392656 21650352
09:09:35 3121 8192000000 10329724656 10758877184 1158610976 12.390 100 88 176 1 1428676096 249958768 20106352
09:10:41 1707 8192000000 10353321136 10768052224 1164240224 12.443 100 72 139 0 1459216752 278073104 16903424
09:11:46 4047 8192000000 10405279008 10831425536 1169421344 12.491 100 54 102 1 1508405408 326102464 12881600
09:12:54 19441 8192000000 10381913936 10879422464 1168079744 12.479 100 31 60 0 1484473040 307194160 9199136
09:14:23 22528 8192000000 10247925488 10689880064 1145830576 12.270 100 24 48 1 1377138992 225301200 6007216
09:15:59 40246 8192000000 10207261904 10686300160 1138228032 12.199 100 26 53 0 1350702976 205990592 6484352
09:17:51 50826 8192000000 10131933936 10574032896 1140699008 12.222 100 18 36 0 1270431600 125724448 4008144
09:19:54 9050 8192000000 10137395088 10567753728 1144042960 12.254 100 51 103 0 1238157520 82365344 11749216
09:21:07 90256 8192000000 10077938000 10622951424 1093821040 11.779 95 11 20 0 1181728560 85598912 2308608
09:23:38 100105 8192000000 10100699792 10531676160 1141746048 12.232 100 26 49 1 1181327584 33444800 6136736
09:26:22 80466 8192000000 10135874432 10580021248 1128006000 12.103 98 39 78 0 1223183456 87343776 7833680
09:28:44 4152 8192000000 10094250784 10538971136 1130358128 12.125 99 73 134 1 1200638896 56605984 13674784
09:29:49 77590 8192000000 10075226448 10518147072 1078548960 11.634 94 19 38 0 1117205344 34179312 4477072
09:32:15 767 8192000000 10129234768 10543820800 1138355808 12.200 99 92 180 2 1218442304 61053856 19032640
09:33:17 16352 8192000000 10171667664 10607005696 1149826848 12.308 100 41 78 1 1268338560 108679264 9832448
09:34:47 25758 8192000000 10164252064 10597720064 1130567808 12.127 99 48 93 1 1257348864 116599184 10181872
09:36:18 32791 8192000000 10171572496 10643148800 1147264352 12.284 100 26 48 1 1274054352 120606752 6183248
09:37:52 25199 8192000000 10141687056 10598928384 1134404368 12.163 99 27 54 0 1233330944 93283424 5643152
09:39:19 31476 8192000000 10149579760 10617532416 1146588080 12.277 100 15 31 0 1259947920 109785984 3573856
09:41:01 79022 8192000000 10109966960 10569338880 1132958736 12.149 99 20 40 0 1216838112 79029312 4850064
09:43:21 71494 8192000000 10066373568 10514489344 1097927120 11.818 96 35 68 1 1133839440 29307984 6604336
09:45:34 32551 8192000000 10069391008 10528219136 1117976960 12.008 99 12 24 1 1163666832 43362704 2327168
09:47:08 90779 8192000000 10053103056 10515918848 1108209744 11.915 98 15 30 0 1151299104 39614544 3474816
09:49:45 83900 8192000000 10049414608 10521333760 1097192224 11.811 97 14 28 0 1156905504 56664288 3048992
09:52:13 63543 8192000000 10127734176 10601459712 1095403424 11.794 96 21 42 0 1184401984 84536064 4462496
09:54:34 40405 8192000000 10150079600 10595651584 1108821232 11.921 97 31 60 0 1203436560 88062112 6553216
09:56:18 49929 8192000000 10134163232 10576584704 1111808896 11.950 99 35 69 0 1240339920 120778032 7752992
09:58:09 14236 8192000000 10082983792 10554150912 1132183536 12.142 100 32 61 0 1202604736 64277856 6143344
09:59:25 8400 8192000000 10119509376 10552086528 1133335184 12.153 100 52 102 5 1210590736 66945536 10310016
10:00:36 61057 8192000000 10082495712 10567716864 1104842000 11.884 97 23 46 0 1176836624 66922432 5072192
10:02:42 9959 8192000000 10098293248 10524712960 1113461264 11.965 99 47 94 1 1180175456 57081360 9632832
10:03:54 75846 8192000000 10113097616 10591780864 1078290672 11.631 95 32 62 1 1186558704 101780880 6487152
10:06:13 90186 8192000000 10067190832 10559729664 1126505312 12.088 100 23 46 0 1173072768 41361312 5206144
10:08:44 47034 8192000000 10075669200 10526928896 1118277776 12.011 99 13 24 0 1167354208 46474464 2601968
10:10:31 46795 8192000000 10072589984 10538852352 1092633152 11.768 97 12 25 0 1160650384 65574720 2442512
10:12:18 58593 8192000000 10028313360 10487570432 1111234064 11.944 99 13 26 0 1147580544 33700112 2646368
10:14:18 46676 8192000000 10048733504 10510114816 1117210400 12.001 99 16 30 0 1141053408 20732256 3110752
10:16:05 32469 8192000000 10044197968 10489495552 1107925344 11.913 98 22 42 0 1145373312 33274080 4173888
10:17:41 24827 8192000000 10097155344 10538213376 1127212224 12.095 99 35 71 0 1190812592 55696848 7903520
10:19:10 34588 8192000000 10125985840 10581962752 1105893968 11.894 97 22 41 0 1202825120 92363072 4568080
10:20:54 21372 8192000000 10191581648 10650361856 1154200704 12.349 100 50 100 0 1291725568 126459328 11065536
10:22:17 30036 8192000000 10218410848 10665426944 1140276624 12.218 99 38 76 1 1303901312 155088400 8536288
10:23:47 31022 8192000000 10246534752 10695696384 1150608912 12.315 99 32 63 0 1347395888 188843584 7943392
10:25:19 49284 8192000000 10188089872 10686885888 1144070944 12.254 99 19 38 0 1295625472 147123056 4431472
10:27:16 21515 8192000000 10068140992 10506559488 1116451536 11.993 98 22 40 2 1137655984 16346384 4858064
10:28:39 9651 8192000000 10175360704 10607038464 1140442752 12.220 100 55 110 0 1266363776 115080320 10840704
10:29:57 141318 8192000000 10074061344 10546229248 1121293936 12.039 99 26 52 0 1185648112 59058832 5295344
10:33:22 53629 8192000000 10081530352 10551828480 1130419152 12.125 100 19 37 1 1187309792 53352048 3538592
10:36:07 83798 8192000000 9870466368 10298159104 898944096 9.888 79 21 42 0 988323328 84505216 4874016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 25-01-2023 10:40:26 to 25-01-2023 10:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 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 25-01-2023 10:40:44 to 25-01-2023 10:40:46.

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          223050
MON$OLDEST_ACTIVE               226380
MON$OLDEST_SNAPSHOT             226380
MON$NEXT_TRANSACTION            226380
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                       2105792
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2891 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2891 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2891 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2891 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 ---
:87: 
:88: mscs-test-161.budzdorov.ru    Wed Jan 25 10:37:52 2023
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Wed Jan 25 10:38:00 2023
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

25.01.23 10:40:47. 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-25 10:40:49 No severe PSQL-related problems occured
25.01.23 10:40:51. End of report.