Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2022 07: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 15585836 kB
MemAvailable 30937144 kB
Buffers 0 kB
Cached 16150344 kB
SwapCached 2528 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-08-14 07:37:20
DTS_END                         2022-08-14 07:37:20.2830
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/45804
TRN_ID                          226343
ATT_ID                          590
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: 8980.69 32226 1077683
customer order: creation 170.22 417 20426
customer order: refuse 15.33 416 1839
order to supplier: creation 47.19 1239 5663
order to supplier: removal 3.13 161563 376
invoice (draft): creation 46.53 3151 5584
invoice (draft): removal 48.84 958 5861
invoice accept: apply 42.81 25467 5137
invoice accept: cancel 44.47 1273 5336
customer reserve: creation 3975.66 243 477079
customer reserve: removal 4296.38 93 515565
realization accept: apply 83.78 272 10054
realization accept: cancel 75.47 179 9057
payment from customer: creation 59.50 173 7140
payment from customer: removal 5.88 130 706
payment to supplier: creation 49.08 185 5890
payment to supplier: removal 3.43 134 411
service: total inventory turnovers 4.13 4065 495
service: total monetary turnovers 8.01 372 961
service: aggregate perf. data 0.83 18407 99
service: refresh index statistics 0.03 425781 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2022 07:39:28 to 14-08-2022 07:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6505
TEST_TIME 2 6569
TEST_TIME 3 5714
TEST_TIME 4 5717
TEST_TIME 5 6157
TEST_TIME 6 6514
TEST_TIME 7 6742
TEST_TIME 8 7003
TEST_TIME 9 7009
TEST_TIME 10 7102
TEST_TIME 11 7156
TEST_TIME 12 7129
TEST_TIME 13 7372
TEST_TIME 14 7200
TEST_TIME 15 7250
TEST_TIME 16 7248
TEST_TIME 17 7283
TEST_TIME 18 7545
TEST_TIME 19 7587
TEST_TIME 20 7696
TEST_TIME 21 8032
TEST_TIME 22 8572
TEST_TIME 23 8588
TEST_TIME 24 8431
TEST_TIME 25 8504
TEST_TIME 26 8636
TEST_TIME 27 8877
TEST_TIME 28 9116
TEST_TIME 29 9348
TEST_TIME 30 9685
TEST_TIME 31 10161
TEST_TIME 32 10606
TEST_TIME 33 10933
TEST_TIME 34 11149
TEST_TIME 35 11056
TEST_TIME 36 10818
TEST_TIME 37 10897
TEST_TIME 38 10880
TEST_TIME 39 10706
TEST_TIME 40 10624
TEST_TIME 41 10477
TEST_TIME 42 10368
TEST_TIME 43 10426
TEST_TIME 44 10485
TEST_TIME 45 10371
TEST_TIME 46 10331
TEST_TIME 47 10218
TEST_TIME 48 10144
TEST_TIME 49 10099
TEST_TIME 50 9942
TEST_TIME 51 9807
TEST_TIME 52 9760
TEST_TIME 53 9776
TEST_TIME 54 9711
TEST_TIME 55 9635
TEST_TIME 56 9546
TEST_TIME 57 9530
TEST_TIME 58 9493
TEST_TIME 59 9506
TEST_TIME 60 9454
TEST_TIME 61 9507
TEST_TIME 62 9610
TEST_TIME 63 9656
TEST_TIME 64 9594
TEST_TIME 65 9663
TEST_TIME 66 9610
TEST_TIME 67 9643
TEST_TIME 68 9653
TEST_TIME 69 9595
TEST_TIME 70 9558
TEST_TIME 71 9589
TEST_TIME 72 9496
TEST_TIME 73 9457
TEST_TIME 74 9377
TEST_TIME 75 9385
TEST_TIME 76 9400
TEST_TIME 77 9406
TEST_TIME 78 9349
TEST_TIME 79 9396
TEST_TIME 80 9357
TEST_TIME 81 9389
TEST_TIME 82 9535
TEST_TIME 83 9561
TEST_TIME 84 9539
TEST_TIME 85 9578
TEST_TIME 86 9577
TEST_TIME 87 9606
TEST_TIME 88 9530
TEST_TIME 89 9477
TEST_TIME 90 9437
TEST_TIME 91 9409
TEST_TIME 92 9415
TEST_TIME 93 9357
TEST_TIME 94 9329
TEST_TIME 95 9297
TEST_TIME 96 9238
TEST_TIME 97 9193
TEST_TIME 98 9131
TEST_TIME 99 9120
TEST_TIME 100 9198
TEST_TIME 101 9185
TEST_TIME 102 9181
TEST_TIME 103 9223
TEST_TIME 104 9234
TEST_TIME 105 9231
TEST_TIME 106 9240
TEST_TIME 107 9208
TEST_TIME 108 9267
TEST_TIME 109 9290
TEST_TIME 110 9299
TEST_TIME 111 9348
TEST_TIME 112 9308
TEST_TIME 113 9243
TEST_TIME 114 9219
TEST_TIME 115 9184
TEST_TIME 116 9169
TEST_TIME 117 9113
TEST_TIME 118 9114
TEST_TIME 119 9070
TEST_TIME 120 8999

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-08-2022 07:39:32 to 14-08-2022 07:39:35.

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 1515648 1515648 0 0.00 0 17792 13 0 0
doc_list_biud 1515642 1515642 0 0.00 0 31828 38 0 0
sp_add_invoice_to_stock 5293 5137 156 2.94 44 197432 25468 0 156
sp_cancel_adding_invoice 5336 5336 0 0.00 6 130226 1274 0 0
sp_cancel_client_order 1839 1839 0 0.00 6 17518 416 0 0
sp_cancel_customer_reserve 515565 515565 0 0.00 1 29726 94 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 4 7975 131 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 4991 135 0 0
sp_cancel_supplier_invoice 5872 5861 11 0.18 2 32056 959 0 11
sp_cancel_supplier_order 376 376 0 0.00 9 1622518 161564 0 0
sp_cancel_write_off 9057 9057 0 0.00 2 19140 179 0 0
sp_client_order 20426 20426 0 0.00 5 29827 418 0 0
sp_customer_reserve 477079 477079 0 0.00 1 34077 243 0 0
sp_fill_shopping_cart 31667 31667 0 0.00 1 14876 83 0 0
sp_fill_shopping_cart_clo_res 477056 477056 0 0.00 0 14425 17 0 0
sp_get_clo_for_invoice 5096 5096 0 0.00 0 17175 231 0 0
sp_kill_cost_storno 15510 15510 0 0.00 0 7650 71 0 0
sp_kill_qstorno_ret_qs2qd 1046424 1046424 0 0.00 0 31826 41 0 0
sp_kill_qty_storno 553183 553183 0 0.00 0 31828 95 0 0
sp_lock_selected_doc 21072 21072 0 0.00 0 11197 9 0 0
sp_make_cost_storno 28179 28179 0 0.00 0 16970 71 0 0
sp_multiply_rows_for_pdistr 28179 28179 0 0.00 0 8385 38 0 0
sp_multiply_rows_for_qdistr 20426 20426 0 0.00 0 29779 160 0 0
sp_pay_from_customer 7140 7140 0 0.00 5 9311 173 0 0
sp_pay_to_supplier 5890 5890 0 0.00 6 19898 186 0 0
sp_payment_common 13030 13030 0 0.00 2 19566 48 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 17473 345 0 0
sp_qd_handle_on_invoice_upd_sts 10431 10431 0 0.00 0 23455 497 0 0
sp_qd_handle_on_reserve_upd_sts 10054 10054 0 0.00 0 15988 66 0 0
sp_reserve_write_off 10054 10054 0 0.00 8 24779 273 0 0
sp_supplier_invoice 5584 5584 0 0.00 9 107152 3152 0 0
sp_supplier_order 5663 5663 0 0.00 8 32193 1239 0 0
srv_aggregate_perf_data 99 99 0 0.00 2612 214399 18407 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 752 147459 38326 0 0
srv_find_qd_qs_mism 1027353 1027353 0 0.00 0 17790 11 0 0
srv_make_invnt_saldo 495 495 0 0.00 78 112490 4066 0 0
srv_make_money_saldo 961 961 0 0.00 1 13108 372 0 0
srv_recalc_idx_stat 4 4 0 0.00 229278 581081 425781 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5140 5140 0 0.00 1 13114 880 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 2 5425 463 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 2084 27 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 2739 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 6591 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 629 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1166 38 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 120032 6719 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2705 23 0 0
t$perf-norm:sp_client_order 20426 20426 0 0.00 0 10126 38 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 818 16 0 0
t$perf-norm:sp_pay_from_customer 7140 7140 0 0.00 0 18037 25 0 0
t$perf-norm:sp_pay_to_supplier 5890 5890 0 0.00 0 13850 22 0 0
t$perf-norm:sp_reserve_write_off 10054 10054 0 0.00 0 6582 22 0 0
t$perf-norm:sp_supplier_invoice 5584 5584 0 0.00 0 9562 32 0 0
t$perf-norm:sp_supplier_order 5663 5663 0 0.00 0 10106 31 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 710 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 54 4 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 739 13 0 0
t$perf-norm:srv_make_money_saldo 961 961 0 0.00 0 811 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 169 48 0 0
v_add_invoice_to_stock 10502 10190 312 2.97 0 7626 26 0 312
v_all_customers 37236 37236 0 0.00 0 90 4 0 0
v_all_suppliers 30986 30986 0 0.00 0 155 4 0 0
v_all_wares 40852 40852 0 0.00 0 143 4 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 2429 20 0 0
v_cancel_client_order 3678 3678 0 0.00 0 6804 11 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 926 12 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 1 523 10 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 3822 14 0 0
v_cancel_supplier_invoice 820 798 22 2.68 0 7638 30 0 22
v_cancel_supplier_order 752 752 0 0.00 0 465 12 0 0
v_cancel_write_off 2072 2072 0 0.00 0 3515 27 0 0
v_max_id_clo_ord 5663 5663 0 0.00 0 1191 5 0 0
v_max_id_clo_res 980 980 0 0.00 0 3160 15 0 0
v_max_id_ord_sup 5578 5578 0 0.00 0 8919 8 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 128 8 0 0
v_max_non_paid_realizn 3769 3769 0 0.00 0 5771 9 0 0
v_min_id_clo_ord 5663 5663 0 0.00 0 2284 9 0 0
v_min_id_clo_res 980 980 0 0.00 0 4561 12 0 0
v_min_id_ord_sup 5578 5578 0 0.00 0 5429 13 0 0
v_min_non_paid_invoice 5890 5890 0 0.00 0 5638 15 0 0
v_min_non_paid_realizn 7140 7140 0 0.00 0 5891 19 0 0
v_our_firm 10358 10358 0 0.00 0 61 5 0 0
v_reserve_write_off 20108 20108 0 0.00 0 7639 11 0 0
x_lock_depdocs_on_canc_invoice 10798 10798 0 0.00 0 12353 110 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 15085 414 0 0
x_make_qstorno_1000_1200 5663 5663 0 0.00 2 32173 1071 0 0
x_make_qstorno_1200_2000 5584 5584 0 0.00 3 107113 2922 0 0
x_make_qstorno_2100_3300 477079 477079 0 0.00 0 33723 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2022 07:39:36 to 14-08-2022 07:39:40.

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:24 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:24 22758 8192000000 9722119584 10018938880 794091696 8.836 100 89 179 0 824145312 8348768 21704848
05:09:51 13685 8192000000 9765155888 10074640384 842978656 9.330 100 88 175 0 875486576 11599472 20908448
05:11:13 16930 8192000000 9802224464 10122665984 869541232 9.595 100 84 168 0 907863776 18850656 19471888
05:12:43 9663 8192000000 9867424704 10202411008 914012976 10.037 100 73 145 0 974105248 43975792 16116480
05:13:56 4517 8192000000 9911589264 10257317888 961554720 10.504 100 90 169 1 1035278576 55390640 18333216
05:15:05 2399 8192000000 9962445888 10313424896 991455872 10.796 100 91 171 1 1080161168 71272768 17432528
05:16:10 1125 8192000000 9998382112 10361200640 1031524240 11.183 100 94 174 1 1120470960 71626432 17320288
05:17:13 1162 8192000000 10044724080 10415308800 1054417488 11.403 100 96 188 2 1164748576 91513488 18817600
05:18:15 859 8192000000 10036062304 10410172416 1064782224 11.502 100 96 185 0 1153558080 70069088 18706768
05:19:17 992 8192000000 10042167696 10420645888 1072532752 11.576 100 88 166 1 1163558608 74294768 16731088
05:20:20 994 8192000000 10067186464 10447949824 1078632896 11.634 100 97 186 1 1186030288 88063760 19333632
05:21:23 2457 8192000000 10030793872 10419277824 1082232336 11.669 100 75 146 0 1149787008 53363168 14191504
05:22:27 884 8192000000 10058654736 10447601664 1090398368 11.746 100 93 178 0 1177447600 68424960 18624272
05:23:29 5926 8192000000 10052871200 10442612736 1096490544 11.804 100 65 128 0 1172130320 62999392 12640384
05:24:36 2478 8192000000 10058193600 10454540288 1100216608 11.840 100 76 145 0 1179786960 65749936 13820416
05:25:39 868 8192000000 10072806272 10463014912 1101975680 11.856 100 94 179 0 1196300128 75575056 18749392
05:26:40 2076 8192000000 10063026576 10457767936 1101149584 11.849 100 80 143 1 1183014160 68678064 13186512
05:27:43 2587 8192000000 10052111264 10448699392 1103453648 11.870 100 74 146 1 1176154032 57856144 14844240
05:28:48 1571 8192000000 10026563152 10416304128 1075867568 11.608 99 74 144 0 1149559312 58066672 15625072
05:29:50 1515 8192000000 10024451936 10418110464 1082408864 11.670 100 66 125 1 1155932544 60431744 13091936
05:30:52 4862 8192000000 10023359328 10411601920 1067507152 11.528 99 72 130 3 1134301616 54069328 12725136
05:31:58 1506 8192000000 10026825872 10418352128 1061036592 11.466 100 85 160 1 1153734848 76380544 16317712
05:33:00 1018 8192000000 10016358048 10396340224 1055344240 11.412 100 86 157 0 1137606976 65566208 16696528
05:34:02 1700 8192000000 9968529456 10351153152 1042556848 11.289 100 79 152 2 1095785936 36358288 16870800
05:35:04 1280 8192000000 9980393088 10353647616 1022693616 11.098 99 87 168 2 1106452480 65956576 17802288
05:36:06 1248 8192000000 9999299184 10373226496 1019703184 11.069 98 94 178 0 1119124864 80142256 19279424
05:37:08 1039 8192000000 9973474912 10346938368 1034876336 11.215 100 91 169 3 1101236144 48567888 17791920
05:38:18 1160 8192000000 9988993584 10362851328 1044615696 11.309 100 84 145 0 1106191088 46663440 14911952
05:39:23 1137 8192000000 10019988656 10399629312 1068404784 11.537 100 79 152 1 1141593776 57604736 15584256
05:40:27 5623 8192000000 10041025664 10426560512 1078194768 11.630 100 79 155 0 1159970128 65391024 16384336
05:41:36 2184 8192000000 10091438480 10477989888 1088904528 11.732 100 89 163 0 1206390176 101454592 16031056
05:42:42 1044 8192000000 10117796432 10505547776 1103213760 11.868 100 86 170 1 1230067184 109708368 17145056
05:43:46 5470 8192000000 10154796512 10548973568 1115757536 11.987 100 73 144 1 1261826864 130941040 15128288
05:44:54 2389 8192000000 10179171040 10568835072 1115241520 11.982 99 79 154 1 1294314528 162373376 16699632
05:46:01 1976 8192000000 10204781904 10596843520 1140526704 12.220 100 90 173 0 1314834800 156447472 17860624
05:47:05 2566 8192000000 10260342368 10667040768 1159106576 12.395 100 81 157 1 1376098560 200162656 16829328
05:48:13 849 8192000000 10276529904 10670080000 1150131488 12.311 100 96 184 2 1386411808 216650848 19629472
05:49:16 2054 8192000000 10311713520 10717667328 1165376848 12.454 100 73 140 1 1420635504 239652640 15606016
05:50:26 787 8192000000 10347942768 10760781824 1179281376 12.583 100 96 185 0 1457907712 257366144 21260192
05:51:30 1205 8192000000 10346592528 10747355136 1166918768 12.468 100 92 177 1 1461360848 273669840 20772240
05:52:35 1065 8192000000 10384329728 10786054144 1169069840 12.488 100 96 188 1 1502050784 312599920 20381024
05:53:39 1296 8192000000 10419156032 10829492224 1166389664 12.463 100 94 178 2 1521392048 335053392 19948992
05:54:44 2409 8192000000 10434969824 10868195328 1160384880 12.407 100 85 170 0 1544183888 362816640 20982368
05:55:50 1002 8192000000 10462654080 10876321792 1176297840 12.556 100 96 189 0 1569637904 371330256 22009808
05:56:54 932 8192000000 10478387472 10904887296 1165139120 12.451 100 97 188 1 1587042256 398981040 22922096
05:57:57 855 8192000000 10470913888 10922356736 1166745776 12.466 100 98 189 1 1573525136 383821104 22958256
05:59:00 741 8192000000 10434667680 10888871936 1158389168 12.388 100 98 192 0 1541278512 359154528 23734816
06:00:03 4422 8192000000 10430687552 10858745856 1164113680 12.442 100 58 110 2 1539845536 361873072 13858784
06:01:17 10545 8192000000 10442898416 10885165056 1151687184 12.325 98 45 84 1 1556822576 394019824 11115568
06:02:36 1168 8192000000 10517346688 10939580416 1169699040 12.494 100 96 187 3 1616137216 424490400 21947776
06:03:39 4859 8192000000 10486085728 10948046848 1148789616 12.298 100 62 120 0 1589942160 426241984 14910560
06:04:57 25589 8192000000 10469999904 10941431808 1150518192 12.314 100 37 74 0 1591270576 431286736 9465648
06:06:27 16544 8192000000 10435769728 10911399936 1133405536 12.153 100 30 60 0 1565634544 424409936 7819072
06:07:47 22477 8192000000 10314097712 10770452480 1132112816 12.141 100 26 50 0 1427085664 288548800 6424048
06:09:12 51101 8192000000 10180393184 10619797504 1099595888 11.834 97 24 46 0 1261595520 155488048 6511584
06:11:34 98409 8192000000 10088400976 10525753344 1095995200 11.800 97 17 32 0 1153175696 54138224 3042272
06:14:53 146811 8192000000 10115340208 10577694720 1134967488 12.168 100 28 52 1 1207839136 66602272 6269376
06:19:17 34212 8192000000 10148466080 10582552576 1141468832 12.229 100 46 87 1 1250045824 98676128 9900864
06:20:58 35806 8192000000 10080366048 10547077120 1109885968 11.931 98 19 38 1 1167319920 53782784 3651168
06:22:37 33412 8192000000 10073139232 10523435008 1122511968 12.051 99 16 26 2 1162791520 37922128 2357424
06:25:59 71573 8192000000 10061297264 10520952832 1118527680 12.013 99 14 28 0 1149816016 28375536 2912800
06:28:45 69372 8192000000 10094564512 10583691264 1124953728 12.074 99 18 36 0 1224322528 95333216 4035584
06:30:55 85853 8192000000 10052144640 10506305536 1106513632 11.899 98 8 16 0 1148000096 39765760 1720704
06:33:24 81001 8192000000 10054161776 10496532480 1095585488 11.796 97 32 65 0 1125378848 22822768 6970592
06:35:47 73863 8192000000 10100992848 10565058560 1125981472 12.083 99 25 50 0 1198282528 66930528 5370528
06:38:20 39683 8192000000 10135420816 10571911168 1142418400 12.238 100 26 50 0 1229869536 81999856 5451280
06:40:07 57053 8192000000 10077984960 10511634432 1136324320 12.181 100 34 65 1 1165371216 21771760 7275136
06:42:10 50471 8192000000 10072514288 10521432064 1118074448 12.009 98 31 62 0 1174582016 50246352 6261216
06:44:03 16891 8192000000 10142011760 10571309056 1140236720 12.218 100 61 122 0 1224587168 72458976 11891472
06:45:21 69557 8192000000 10118914480 10608197632 1134071072 12.160 100 32 65 0 1242430896 100979840 7379984
06:48:24 37498 8192000000 10053695616 10567348224 1123174336 12.057 100 17 34 0 1211755488 85408640 3172512
06:50:02 59945 8192000000 10097859776 10573832192 1108884704 11.922 98 10 20 0 1181424720 70316240 2223776
06:52:02 57451 8192000000 10092120320 10568921088 1095735840 11.797 97 29 55 1 1180962512 78898384 6328288
06:54:00 59624 8192000000 10098923328 10542215168 1118364304 12.012 98 27 52 1 1179288480 55259440 5664736
06:56:04 25136 8192000000 10145995408 10585522176 1127080960 12.094 99 41 82 0 1226531056 90739152 8710944
06:57:34 52836 8192000000 10176560240 10631798784 1103471872 11.871 97 22 44 0 1251458496 142932080 5054544
06:59:27 48861 8192000000 10102543584 10539900928 1101648272 11.853 98 29 58 0 1214139760 106206352 6285136
07:01:17 46877 8192000000 10115069328 10536009728 1115866896 11.988 99 37 74 0 1182767200 59616512 7283792
07:03:11 66154 8192000000 10111589808 10541584384 1123978432 12.065 99 30 54 0 1188298688 58173200 6147056
07:05:26 147458 8192000000 10051471584 10510536704 1092055568 11.762 97 22 44 0 1133362128 36726160 4580400
07:08:56 60168 8192000000 10057305792 10550255616 1124506512 12.070 100 12 23 0 1182062928 55412224 2144192
07:10:57 56165 8192000000 10035313664 10490449920 1111040448 11.942 98 18 35 0 1163069536 47942816 4086272
07:13:08 49801 8192000000 10060762608 10508480512 1120044080 12.027 99 23 45 0 1163789568 38754752 4990736
07:14:59 67830 8192000000 10131406096 10584764416 1125971616 12.083 99 17 34 0 1202003104 72158240 3873248
07:17:07 48084 8192000000 10053824736 10495594496 1127458160 12.097 99 13 26 0 1169900880 40583312 1859408
07:18:59 18718 8192000000 10081349360 10516463616 1122186160 12.048 99 40 75 1 1164872640 35707008 6979472
07:20:25 48492 8192000000 10108184224 10560012288 1139038528 12.206 100 20 40 0 1223712304 80515632 4158144
07:22:14 35347 8192000000 10109650480 10564038656 1129506208 12.117 99 33 63 1 1202048432 66531936 6010288
07:24:00 37027 8192000000 10137709808 10565541888 1126264384 12.086 99 42 80 0 1222637856 88268208 8105264
07:25:39 73123 8192000000 10123865200 10591313920 1116808768 11.997 98 43 85 0 1187582656 62182016 8591872
07:27:57 82912 8192000000 10073273248 10528075776 1126708256 12.090 99 33 62 1 1179543760 46474224 6361280
07:30:21 75853 8192000000 10080181744 10524962816 1130690192 12.128 100 49 98 0 1183064400 42619376 9754832
07:32:37 95830 8192000000 10063887872 10530910208 1125511808 12.079 100 18 34 1 1193877872 64690368 3675696
07:36:03 79035 8192000000 10074163840 10522955776 1097398816 11.813 97 17 33 1 1165753488 64411216 3943456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-08-2022 07:39:42 to 14-08-2022 07:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 14-08-2022 07:39:59 to 14-08-2022 07:40:00.

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          224625
MON$OLDEST_ACTIVE               227753
MON$OLDEST_SNAPSHOT             227753
MON$NEXT_TRANSACTION            227753
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                       2072480
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.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:242: 
:243: mscs-test-161.budzdorov.ru    Sun Aug 14 07:37:31 2022
:244:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:245: 
--- end of diff output ---

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

14.08.22 07:40:02. 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-08-14 07:40:04 No severe PSQL-related problems occured
14.08.22 07:40:05. End of report.