Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.06.2022 13: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 15897348 kB
MemAvailable 31278824 kB
Buffers 0 kB
Cached 15839088 kB
SwapCached 2460 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-06-19 13:37:18
DTS_END                         2022-06-19 13:37:18.7240
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/57404
TRN_ID                          221464
ATT_ID                          626
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: 9355.44 25100 1122653
customer order: creation 171.32 464 20558
customer order: refuse 14.67 363 1760
order to supplier: creation 44.81 1291 5377
order to supplier: removal 3.33 167977 400
invoice (draft): creation 45.47 2877 5456
invoice (draft): removal 51.90 963 6228
invoice accept: apply 42.02 26256 5042
invoice accept: cancel 47.44 1121 5693
customer reserve: creation 3979.00 246 477480
customer reserve: removal 4648.84 94 557861
realization accept: apply 83.78 266 10053
realization accept: cancel 91.99 146 11039
payment from customer: creation 58.80 169 7056
payment from customer: removal 5.53 129 664
payment to supplier: creation 50.32 190 6038
payment to supplier: removal 3.56 108 427
service: total inventory turnovers 3.98 4052 477
service: total monetary turnovers 7.72 349 926
service: aggregate perf. data 0.94 15889 113
service: refresh index statistics 0.04 279044 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-06-2022 13:39:33 to 19-06-2022 13:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10025
TEST_TIME 2 6337
TEST_TIME 3 5864
TEST_TIME 4 5658
TEST_TIME 5 5607
TEST_TIME 6 5576
TEST_TIME 7 5705
TEST_TIME 8 5664
TEST_TIME 9 5800
TEST_TIME 10 5988
TEST_TIME 11 6390
TEST_TIME 12 6590
TEST_TIME 13 6922
TEST_TIME 14 7138
TEST_TIME 15 7531
TEST_TIME 16 7729
TEST_TIME 17 7827
TEST_TIME 18 7801
TEST_TIME 19 7918
TEST_TIME 20 8035
TEST_TIME 21 8128
TEST_TIME 22 8535
TEST_TIME 23 8999
TEST_TIME 24 8955
TEST_TIME 25 8845
TEST_TIME 26 9279
TEST_TIME 27 9322
TEST_TIME 28 9436
TEST_TIME 29 9649
TEST_TIME 30 10322
TEST_TIME 31 10309
TEST_TIME 32 10957
TEST_TIME 33 10895
TEST_TIME 34 11404
TEST_TIME 35 11391
TEST_TIME 36 11398
TEST_TIME 37 11363
TEST_TIME 38 11291
TEST_TIME 39 11190
TEST_TIME 40 11107
TEST_TIME 41 10847
TEST_TIME 42 10733
TEST_TIME 43 10618
TEST_TIME 44 10514
TEST_TIME 45 10501
TEST_TIME 46 10382
TEST_TIME 47 10320
TEST_TIME 48 10440
TEST_TIME 49 10313
TEST_TIME 50 10284
TEST_TIME 51 10212
TEST_TIME 52 10104
TEST_TIME 53 10270
TEST_TIME 54 10199
TEST_TIME 55 10186
TEST_TIME 56 10276
TEST_TIME 57 10197
TEST_TIME 58 10231
TEST_TIME 59 10274
TEST_TIME 60 10201
TEST_TIME 61 10245
TEST_TIME 62 10222
TEST_TIME 63 10277
TEST_TIME 64 10299
TEST_TIME 65 10368
TEST_TIME 66 10400
TEST_TIME 67 10292
TEST_TIME 68 10147
TEST_TIME 69 10150
TEST_TIME 70 10109
TEST_TIME 71 10047
TEST_TIME 72 9983
TEST_TIME 73 9950
TEST_TIME 74 9944
TEST_TIME 75 9856
TEST_TIME 76 9771
TEST_TIME 77 9787
TEST_TIME 78 9733
TEST_TIME 79 9644
TEST_TIME 80 9623
TEST_TIME 81 9633
TEST_TIME 82 9641
TEST_TIME 83 9645
TEST_TIME 84 9604
TEST_TIME 85 9604
TEST_TIME 86 9615
TEST_TIME 87 9578
TEST_TIME 88 9690
TEST_TIME 89 9735
TEST_TIME 90 9767
TEST_TIME 91 9726
TEST_TIME 92 9678
TEST_TIME 93 9603
TEST_TIME 94 9583
TEST_TIME 95 9569
TEST_TIME 96 9522
TEST_TIME 97 9490
TEST_TIME 98 9479
TEST_TIME 99 9487
TEST_TIME 100 9472
TEST_TIME 101 9446
TEST_TIME 102 9426
TEST_TIME 103 9399
TEST_TIME 104 9423
TEST_TIME 105 9398
TEST_TIME 106 9436
TEST_TIME 107 9426
TEST_TIME 108 9526
TEST_TIME 109 9661
TEST_TIME 110 9687
TEST_TIME 111 9648
TEST_TIME 112 9598
TEST_TIME 113 9555
TEST_TIME 114 9528
TEST_TIME 115 9496
TEST_TIME 116 9493
TEST_TIME 117 9463
TEST_TIME 118 9432
TEST_TIME 119 9443
TEST_TIME 120 9379

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-06-2022 13:39:36 to 19-06-2022 13:39:39.

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 1560117 1560117 0 0.00 0 18173 13 0 0
doc_list_biud 1560110 1560110 0 0.00 0 33024 39 0 0
sp_add_invoice_to_stock 5185 5042 143 2.75 52 222174 26257 0 143
sp_cancel_adding_invoice 5693 5693 0 0.00 7 102323 1122 0 0
sp_cancel_client_order 1760 1760 0 0.00 6 10762 363 0 0
sp_cancel_customer_reserve 557861 557861 0 0.00 1 26013 95 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 5679 129 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 5238 108 0 0
sp_cancel_supplier_invoice 6237 6228 9 0.14 2 32472 964 0 9
sp_cancel_supplier_order 400 400 0 0.00 9 1267259 167978 0 0
sp_cancel_write_off 11039 11039 0 0.00 2 16425 146 0 0
sp_client_order 20558 20558 0 0.00 6 25992 465 0 0
sp_customer_reserve 477480 477480 0 0.00 1 155742 247 0 0
sp_fill_shopping_cart 31376 31376 0 0.00 2 22146 78 0 0
sp_fill_shopping_cart_clo_res 477447 477447 0 0.00 0 19290 19 0 0
sp_get_clo_for_invoice 4999 4999 0 0.00 0 17683 283 0 0
sp_kill_cost_storno 17823 17823 0 0.00 0 8934 64 0 0
sp_kill_qstorno_ret_qs2qd 1133389 1133389 0 0.00 0 32429 41 0 0
sp_kill_qty_storno 598032 598032 0 0.00 0 33023 95 0 0
sp_lock_selected_doc 20800 20800 0 0.00 0 7618 8 0 0
sp_make_cost_storno 28146 28146 0 0.00 0 12404 81 0 0
sp_multiply_rows_for_pdistr 28145 28145 0 0.00 0 9631 34 0 0
sp_multiply_rows_for_qdistr 20558 20558 0 0.00 0 10795 174 0 0
sp_pay_from_customer 7056 7056 0 0.00 5 13597 170 0 0
sp_pay_to_supplier 6038 6038 0 0.00 6 13451 190 0 0
sp_payment_common 13093 13093 0 0.00 2 10072 50 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 10756 295 0 0
sp_qd_handle_on_invoice_upd_sts 10692 10692 0 0.00 0 33022 502 0 0
sp_qd_handle_on_reserve_upd_sts 10052 10052 0 0.00 0 15314 62 0 0
sp_reserve_write_off 10053 10053 0 0.00 8 15407 266 0 0
sp_supplier_invoice 5456 5456 0 0.00 10 83223 2878 0 0
sp_supplier_order 5377 5377 0 0.00 7 32979 1292 0 0
srv_aggregate_perf_data 113 113 0 0.00 2349 163347 15889 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 802 129210 35218 0 0
srv_find_qd_qs_mism 1071992 1071992 0 0.00 0 18131 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 80 65552 4053 0 0
srv_make_money_saldo 926 926 0 0.00 1 15329 350 0 0
srv_recalc_idx_stat 5 5 0 0.00 65496 445165 279044 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5048 5048 0 0.00 1 12446 895 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 2 6859 408 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 5797 31 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 3679 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 8101 36 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1642 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 5448 39 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 115356 6819 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 590 14 0 0
t$perf-norm:sp_client_order 20558 20558 0 0.00 0 5525 34 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 5299 17 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 4318 18 0 0
t$perf-norm:sp_pay_to_supplier 6038 6038 0 0.00 0 6598 20 0 0
t$perf-norm:sp_reserve_write_off 10053 10053 0 0.00 0 3864 23 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 4918 32 0 0
t$perf-norm:sp_supplier_order 5377 5377 0 0.00 0 3785 29 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 557 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 122 5 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 6009 23 0 0
t$perf-norm:srv_make_money_saldo 926 926 0 0.00 0 552 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 229 62 0 0
v_add_invoice_to_stock 10274 9988 286 2.78 0 4948 24 0 286
v_all_customers 37499 37499 0 0.00 0 112 3 0 0
v_all_suppliers 30840 30840 0 0.00 0 83 4 0 0
v_all_wares 41108 41108 0 0.00 0 103 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 2042 16 0 0
v_cancel_client_order 3520 3520 0 0.00 0 4885 12 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2377 13 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 4039 13 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1587 12 0 0
v_cancel_supplier_invoice 818 800 18 2.20 0 980 25 0 18
v_cancel_supplier_order 800 800 0 0.00 0 1927 15 0 0
v_cancel_write_off 2014 2014 0 0.00 0 2543 22 0 0
v_max_id_clo_ord 5376 5376 0 0.00 0 1301 5 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 1366 14 0 0
v_max_id_ord_sup 5445 5445 0 0.00 0 4274 7 0 0
v_max_non_paid_invoice 1439 1439 0 0.00 0 992 8 0 0
v_max_non_paid_realizn 3670 3670 0 0.00 0 5338 9 0 0
v_min_id_clo_ord 5376 5376 0 0.00 0 3031 9 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 4842 12 0 0
v_min_id_ord_sup 5445 5445 0 0.00 0 4354 10 0 0
v_min_non_paid_invoice 6038 6038 0 0.00 0 7248 20 0 0
v_min_non_paid_realizn 7054 7054 0 0.00 0 10068 24 0 0
v_our_firm 10378 10378 0 0.00 0 85 4 0 0
v_reserve_write_off 20104 20104 0 0.00 0 5216 12 0 0
x_lock_depdocs_on_canc_invoice 11521 11521 0 0.00 0 12250 115 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 4347 351 0 0
x_make_qstorno_1000_1200 5377 5377 0 0.00 2 32806 1125 0 0
x_make_qstorno_1200_2000 5456 5456 0 0.00 5 79405 2674 0 0
x_make_qstorno_2100_3300 477479 477479 0 0.00 0 155734 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2022 13:39:40 to 19-06-2022 13:39:44.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:22 6 8192000000 8799338256 8810778624 5349552 0.065 2 1 2 0 5447168 61840 35776
11:08:23 43635 8192000000 9717601584 10017861632 794389472 8.839 100 89 178 0 824704496 8217408 22097616
11:10:11 17796 8192000000 9754299568 10061832192 828780816 9.187 100 86 172 0 863376432 14144784 20450832
11:11:35 18434 8192000000 9797552624 10121240576 870104320 9.601 100 78 156 0 911575744 23699952 17771472
11:12:57 10818 8192000000 9880835248 10215796736 926078992 10.156 100 89 177 0 987883520 42242976 19561552
11:14:13 5403 8192000000 9932271024 10278223872 967509632 10.562 100 84 168 0 1040187968 55104432 17573904
11:15:21 3353 8192000000 9980820848 10337513472 1006364704 10.940 100 93 180 1 1095739760 71259696 18115360
11:16:26 1435 8192000000 9996047072 10357964800 1028505536 11.154 100 90 169 2 1115914304 70606096 16802672
11:17:28 989 8192000000 10030894528 10400423936 1043864912 11.302 100 95 185 1 1148510800 85678192 18967696
11:18:30 871 8192000000 10019832288 10394984448 1061894864 11.475 100 94 186 1 1144805680 63680704 19230112
11:19:32 4770 8192000000 10026432688 10399236096 1067340208 11.527 100 96 179 0 1142050240 57557232 17152800
11:20:38 954 8192000000 10048060336 10433945600 1070445520 11.556 100 87 169 0 1169203552 80285104 18472928
11:21:40 1521 8192000000 10061633344 10447261696 1074998016 11.600 100 80 156 2 1188803712 97900768 15904928
11:22:42 1054 8192000000 10036575392 10422067200 1082457968 11.671 100 97 185 1 1158566576 56611584 19497024
11:23:44 1246 8192000000 10052284128 10435514368 1085660032 11.701 100 93 179 0 1174718016 70669168 18388816
11:24:46 1202 8192000000 10075805120 10459258880 1091949488 11.761 100 95 185 1 1199740736 89326336 18464912
11:25:48 1235 8192000000 10060687088 10449367040 1093664160 11.777 100 95 187 0 1181825392 68147424 20013808
11:26:50 1198 8192000000 10071723616 10460307456 1098197360 11.821 100 99 191 1 1194754368 75506208 21050800
11:27:53 1201 8192000000 10031253440 10414739456 1079106336 11.639 99 95 182 0 1151481248 54444096 17930816
11:28:55 2702 8192000000 10042038736 10429100032 1087192208 11.716 100 72 142 4 1159771440 59056064 13523168
11:29:59 2019 8192000000 10047465328 10438148096 1090409536 11.747 100 77 148 2 1173779568 68503616 14866416
11:31:02 1498 8192000000 10012932752 10388279296 1049720208 11.358 100 96 182 1 1136136624 66991584 19424832
11:32:04 1110 8192000000 10027414160 10407620608 1057847728 11.436 100 92 179 1 1149514960 72515568 19151664
11:33:06 1382 8192000000 10009787968 10384404480 1039845152 11.263 98 95 177 1 1133261552 74346672 19069728
11:34:08 1334 8192000000 9963981808 10338660352 1010148480 10.977 98 72 139 1 1088595568 64580736 13866352
11:35:11 1404 8192000000 10010553472 10387025920 1050057008 11.361 100 90 165 2 1136138960 66661824 19420128
11:36:13 2649 8192000000 9999059584 10381537280 1043311712 11.296 100 68 129 1 1117825744 62508048 12005984
11:37:17 1455 8192000000 9996422256 10370674688 1048133152 11.343 100 93 175 1 1120385632 52940576 19311904
11:38:23 11833 8192000000 10005203600 10391535616 1067011808 11.524 100 73 128 1 1125129568 44879344 13238416
11:39:43 3339 8192000000 10047106176 10432282624 1083395776 11.680 100 70 137 2 1169194896 72251456 13547664
11:40:51 12417 8192000000 10064543920 10465374208 1087735456 11.721 100 64 125 0 1192004592 91345616 12923520
11:42:07 2813 8192000000 10118270960 10505314304 1108682160 11.920 100 84 161 0 1235670720 110652272 16336288
11:43:16 4327 8192000000 10152665488 10551980032 1119008768 12.018 100 67 133 1 1265313392 131803584 14501040
11:44:24 4498 8192000000 10176629984 10569424896 1126334064 12.087 100 77 149 0 1295756912 153757760 15665088
11:45:33 4298 8192000000 10201232368 10601119744 1135266032 12.171 100 73 143 1 1322496864 170872400 16358432
11:46:41 1243 8192000000 10257385616 10652704768 1152886768 12.337 100 91 175 2 1370042832 199336192 17819872
11:47:46 4301 8192000000 10271773680 10670374912 1152556976 12.333 100 87 173 0 1388471040 216578624 19335440
11:48:53 1277 8192000000 10283532832 10702643200 1159649296 12.400 100 89 167 1 1391111232 213548032 17913904
11:49:59 2203 8192000000 10321190528 10733944832 1166787408 12.467 100 95 182 4 1434121696 246224960 21109328
11:51:09 1030 8192000000 10324916288 10728988672 1173456800 12.529 100 94 186 0 1438638496 243779280 21402416
11:52:12 1041 8192000000 10359128384 10785828864 1182374080 12.612 100 91 173 0 1468801888 265162336 21265472
11:53:16 941 8192000000 10382249296 10794057728 1186395744 12.650 100 97 189 1 1490259840 283374448 20489648
11:54:19 1207 8192000000 10413794704 10832945152 1184510016 12.632 100 92 177 1 1523694464 318097632 21086816
11:55:23 1002 8192000000 10450743264 10860261376 1178948368 12.580 100 98 186 2 1559125040 359042128 21134544
11:56:27 1028 8192000000 10479925376 10904576000 1193290576 12.714 100 97 192 0 1581174848 365096208 22788064
11:57:30 920 8192000000 10520565136 10946572288 1192410576 12.706 100 99 192 2 1623370176 407851888 23107712
11:58:34 930 8192000000 10531581472 10968961024 1204925072 12.822 100 96 190 1 1633957248 406917840 22114336
11:59:38 797 8192000000 10505045920 10978443264 1197024592 12.749 100 94 187 0 1611735872 390787200 23924080
12:00:40 8108 8192000000 10482609936 10937520128 1185844448 12.645 100 61 122 0 1587533792 386591792 15097552
12:01:52 5620 8192000000 10509440016 10943250432 1177297088 12.565 100 50 98 0 1619039584 428791504 12950992
12:03:27 16596 8192000000 10473208960 10935746560 1166258000 12.462 99 38 76 0 1580092320 403983904 9850416
12:04:55 17879 8192000000 10470855632 10937823232 1134963008 12.168 99 43 86 0 1553250784 407102688 11185088
12:06:15 18609 8192000000 10453010928 10909274112 1146784976 12.279 100 40 78 0 1578654144 421913904 9955264
12:07:59 26232 8192000000 10292904768 10769215488 1108125952 11.915 97 21 42 0 1368416208 254786736 5503520
12:09:52 33624 8192000000 10193000464 10659999744 1125429792 12.078 99 17 34 2 1278800848 149106800 4264256
12:11:27 68221 8192000000 10094315008 10548121600 1109862112 11.931 97 21 42 0 1165146960 50478144 4806704
12:13:37 91179 8192000000 10105580928 10589626368 1109802224 11.931 97 12 24 0 1167830496 55692832 2335440
12:16:42 129210 8192000000 10062673472 10498048000 1091414064 11.756 95 23 46 2 1134993680 38650080 4929536
12:19:53 17755 8192000000 10104504368 10530844672 1134766784 12.166 100 51 98 1 1207768656 63100880 9900992
12:21:12 103537 8192000000 10095248912 10542739456 1111744544 11.949 97 47 92 2 1183583568 61387920 10451104
12:23:56 20761 8192000000 10166080464 10599661568 1131491696 12.135 98 55 108 2 1237123408 94649216 10982496
12:25:21 26392 8192000000 10121691760 10606477312 1146484464 12.276 100 28 56 0 1237290656 84773424 6032768
12:26:49 75902 8192000000 10151819600 10603634688 1128990144 12.112 98 22 44 0 1211925648 77884272 5051232
12:29:05 70607 8192000000 10145921808 10654822400 1159448720 12.398 100 19 38 1 1284543568 120403760 4691088
12:31:22 35504 8192000000 10121326176 10578288640 1105706624 11.892 96 27 54 2 1159325152 47660336 5958192
12:33:05 47414 8192000000 10070710176 10524385280 1135648000 12.175 99 14 28 0 1201175408 61898480 3628928
12:34:58 14124 8192000000 10154687568 10578567168 1144977008 12.262 99 67 127 1 1228966400 69002960 14986432
12:36:14 48000 8192000000 10129903520 10596773888 1159283136 12.397 100 21 42 0 1235189264 71534400 4371728
12:38:03 26358 8192000000 10111827408 10562703360 1168408416 12.482 100 32 62 1 1239407984 64554672 6444896
12:39:29 39714 8192000000 10146167888 10581561344 1134224528 12.161 97 25 50 1 1221570896 82501792 4844576
12:41:15 84217 8192000000 10139224496 10630004736 1118492592 12.013 96 22 44 0 1235420432 111844832 5083008
12:43:40 113608 8192000000 10120160816 10563207168 1110754400 11.940 96 11 22 0 1193573328 80396224 2422704
12:46:41 25026 8192000000 10109973440 10562256896 1154097824 12.348 100 48 96 0 1208938960 44914656 9926480
12:48:07 51717 8192000000 10100080144 10558586880 1159186880 12.396 100 11 22 0 1217613504 56247392 2179232
12:50:10 78098 8192000000 10097601904 10560561152 1122969952 12.055 97 9 18 0 1176694832 51802752 1922128
12:53:13 75566 8192000000 10088074464 10551279616 1122455952 12.050 98 15 29 2 1175585536 49362544 3767040
12:55:29 92499 8192000000 10088434224 10543136768 1082764832 11.674 95 14 28 0 1134522352 48773408 2984112
12:58:14 44095 8192000000 10069812256 10521923584 1089925728 11.742 96 19 35 1 1148501808 54818480 3757600
12:59:59 29906 8192000000 10050912080 10480324608 1084396704 11.689 96 29 58 0 1122686928 32256192 6034032
13:01:30 20709 8192000000 10115426656 10540851200 1122572816 12.051 100 55 109 1 1200411632 66503952 11334864
13:02:52 47869 8192000000 10169939312 10615586816 1110327008 11.936 97 36 72 0 1210431520 92708384 7396128
13:04:44 37800 8192000000 10120292768 10583515136 1119807440 12.025 99 31 62 1 1233723200 107542288 6373472
13:06:25 18035 8192000000 10081216896 10536079360 1136097664 12.179 100 29 56 1 1183193568 40100464 6995440
13:07:44 35053 8192000000 10087739680 10532671488 1112853408 11.959 98 27 54 1 1166501200 48542128 5105664
13:09:24 113808 8192000000 10132788656 10584862720 1145814960 12.270 100 47 94 2 1249940384 94699664 9425760
13:13:08 69739 8192000000 10094185168 10587209728 1141955040 12.234 100 10 18 2 1204559552 61196080 1408432
13:15:17 47602 8192000000 10103914592 10549223424 1117950464 12.008 98 23 47 2 1184770944 62183328 4637152
13:17:06 38659 8192000000 10100221568 10536472576 1085828160 11.703 95 26 52 0 1153429984 62128656 5473168
13:18:46 20051 8192000000 10136264304 10567933952 1117467152 12.003 97 38 75 1 1214455360 88495504 8492704
13:20:11 17592 8192000000 10191295424 10622582784 1135328640 12.172 99 38 74 2 1284949216 141152256 8468320
13:21:30 15490 8192000000 10180278320 10624413696 1135344272 12.172 99 26 48 2 1271238896 129064736 6829888
13:22:53 16044 8192000000 10242412848 10676060160 1143571440 12.249 100 60 119 0 1324729584 167978912 13179232
13:24:11 54204 8192000000 10221069136 10681004032 1141717440 12.232 99 27 54 1 1308554064 160766112 6070512
13:26:05 89099 8192000000 10099838304 10587136000 1092401568 11.765 96 16 32 1 1153378480 57761632 3215280
13:28:35 19285 8192000000 10093044752 10521878528 1129279696 12.115 100 51 99 1 1176590464 36951536 10359232
13:29:54 88405 8192000000 10101369872 10552016896 1134324256 12.162 100 21 40 0 1209377008 70393232 4659520
13:32:23 3598 8192000000 10130899744 10562908160 1149642128 12.306 100 58 109 2 1229159696 67730368 11787200
13:33:28 91987 8192000000 10113521072 10588803072 1148877872 12.299 100 13 26 1 1217298976 65057168 3363936
13:36:01 57715 8192000000 10075112176 10554134528 1126864928 12.092 98 17 34 1 1200465568 69741120 3859520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-06-2022 13:39:46 to 19-06-2022 13:40:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-06-2022 13:40:03 to 19-06-2022 13:40:05.

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          221463
MON$OLDEST_ACTIVE               224914
MON$OLDEST_SNAPSHOT             224914
MON$NEXT_TRANSACTION            224914
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                       2077728
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:212: 
:213: mscs-test-161.budzdorov.ru    Sun Jun 19 13:37:48 2022
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:215: 
:216: 
:217: mscs-test-161.budzdorov.ru    Sun Jun 19 13:37:53 2022
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Sun Jun 19 13:37:54 2022
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:223: 
--- end of diff output ---

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

19.06.22 13:40:06. 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-06-19 13:40:09 No severe PSQL-related problems occured
19.06.22 13:40:10. End of report.