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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16294048 kB
MemAvailable 31542264 kB
Buffers 20 kB
Cached 15381092 kB
SwapCached 2360 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-04-09 04:37:13
DTS_END                         2022-04-09 04:37:13.7930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40430
TRN_ID                          216998
ATT_ID                          620
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: 8882.47 26259 1065896
customer order: creation 163.76 487 19651
customer order: refuse 14.36 465 1723
order to supplier: creation 45.00 1410 5400
order to supplier: removal 3.37 170578 404
invoice (draft): creation 44.68 3247 5362
invoice (draft): removal 50.47 1071 6056
invoice accept: apply 41.04 27733 4925
invoice accept: cancel 45.32 1340 5438
customer reserve: creation 3801.61 263 456193
customer reserve: removal 4379.64 102 525557
realization accept: apply 81.63 335 9795
realization accept: cancel 85.42 172 10250
payment from customer: creation 57.70 197 6924
payment from customer: removal 5.81 138 697
payment to supplier: creation 47.17 220 5660
payment to supplier: removal 3.40 132 408
service: total inventory turnovers 3.63 4845 436
service: total monetary turnovers 7.66 445 919
service: aggregate perf. data 0.78 21661 93
service: refresh index statistics 0.04 290329 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-04-2022 04:39:17 to 09-04-2022 04:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10179
TEST_TIME 2 6443
TEST_TIME 3 6423
TEST_TIME 4 5657
TEST_TIME 5 6302
TEST_TIME 6 6215
TEST_TIME 7 6054
TEST_TIME 8 5841
TEST_TIME 9 5837
TEST_TIME 10 5935
TEST_TIME 11 6047
TEST_TIME 12 5921
TEST_TIME 13 6170
TEST_TIME 14 6711
TEST_TIME 15 6829
TEST_TIME 16 6786
TEST_TIME 17 6843
TEST_TIME 18 7024
TEST_TIME 19 7011
TEST_TIME 20 7139
TEST_TIME 21 7280
TEST_TIME 22 7294
TEST_TIME 23 7379
TEST_TIME 24 7710
TEST_TIME 25 8086
TEST_TIME 26 8214
TEST_TIME 27 8260
TEST_TIME 28 8767
TEST_TIME 29 8909
TEST_TIME 30 9452
TEST_TIME 31 9526
TEST_TIME 32 9692
TEST_TIME 33 9972
TEST_TIME 34 10225
TEST_TIME 35 10070
TEST_TIME 36 10055
TEST_TIME 37 10195
TEST_TIME 38 10535
TEST_TIME 39 10412
TEST_TIME 40 10314
TEST_TIME 41 10311
TEST_TIME 42 10173
TEST_TIME 43 10078
TEST_TIME 44 10014
TEST_TIME 45 9895
TEST_TIME 46 9986
TEST_TIME 47 9852
TEST_TIME 48 9948
TEST_TIME 49 9788
TEST_TIME 50 9684
TEST_TIME 51 9641
TEST_TIME 52 9620
TEST_TIME 53 9573
TEST_TIME 54 9523
TEST_TIME 55 9437
TEST_TIME 56 9446
TEST_TIME 57 9414
TEST_TIME 58 9415
TEST_TIME 59 9347
TEST_TIME 60 9337
TEST_TIME 61 9287
TEST_TIME 62 9209
TEST_TIME 63 9225
TEST_TIME 64 9273
TEST_TIME 65 9249
TEST_TIME 66 9371
TEST_TIME 67 9356
TEST_TIME 68 9491
TEST_TIME 69 9467
TEST_TIME 70 9432
TEST_TIME 71 9408
TEST_TIME 72 9481
TEST_TIME 73 9529
TEST_TIME 74 9530
TEST_TIME 75 9630
TEST_TIME 76 9595
TEST_TIME 77 9588
TEST_TIME 78 9556
TEST_TIME 79 9481
TEST_TIME 80 9401
TEST_TIME 81 9329
TEST_TIME 82 9565
TEST_TIME 83 9515
TEST_TIME 84 9453
TEST_TIME 85 9406
TEST_TIME 86 9442
TEST_TIME 87 9416
TEST_TIME 88 9374
TEST_TIME 89 9363
TEST_TIME 90 9321
TEST_TIME 91 9242
TEST_TIME 92 9194
TEST_TIME 93 9169
TEST_TIME 94 9150
TEST_TIME 95 9136
TEST_TIME 96 9098
TEST_TIME 97 9032
TEST_TIME 98 9011
TEST_TIME 99 9065
TEST_TIME 100 9051
TEST_TIME 101 9049
TEST_TIME 102 9039
TEST_TIME 103 9039
TEST_TIME 104 9061
TEST_TIME 105 9102
TEST_TIME 106 9084
TEST_TIME 107 9185
TEST_TIME 108 9186
TEST_TIME 109 9172
TEST_TIME 110 9229
TEST_TIME 111 9222
TEST_TIME 112 9173
TEST_TIME 113 9153
TEST_TIME 114 9089
TEST_TIME 115 9069
TEST_TIME 116 9067
TEST_TIME 117 9060
TEST_TIME 118 9019
TEST_TIME 119 8967
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-04-2022 04:39:21 to 09-04-2022 04:39:24.

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 1486150 1486150 0 0.00 0 22920 14 0 0
doc_list_biud 1486132 1486132 0 0.00 0 82372 43 0 0
sp_add_invoice_to_stock 5062 4925 137 2.70 70 221217 27734 0 137
sp_cancel_adding_invoice 5438 5438 0 0.00 5 87153 1340 0 0
sp_cancel_client_order 1723 1723 0 0.00 6 15086 466 0 0
sp_cancel_customer_reserve 525557 525557 0 0.00 1 69629 102 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 4 4452 138 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 5148 133 0 0
sp_cancel_supplier_invoice 6066 6056 10 0.16 2 82387 1071 0 10
sp_cancel_supplier_order 404 404 0 0.00 16 1507287 170579 0 0
sp_cancel_write_off 10250 10250 0 0.00 2 9734 172 0 0
sp_client_order 19651 19651 0 0.00 5 31092 488 0 0
sp_customer_reserve 456193 456193 0 0.00 1 69835 264 0 0
sp_fill_shopping_cart 30399 30399 0 0.00 1 11932 86 0 0
sp_fill_shopping_cart_clo_res 456153 456153 0 0.00 0 24716 19 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 41534 313 0 0
sp_kill_cost_storno 16793 16793 0 0.00 0 18763 82 0 0
sp_kill_qstorno_ret_qs2qd 1067824 1067824 0 0.00 0 82370 45 0 0
sp_kill_qty_storno 564102 564102 0 0.00 0 82371 104 0 0
sp_lock_selected_doc 20324 20324 0 0.00 0 9109 9 0 0
sp_make_cost_storno 27260 27260 0 0.00 0 20465 95 0 0
sp_multiply_rows_for_pdistr 27260 27260 0 0.00 0 9279 47 0 0
sp_multiply_rows_for_qdistr 19651 19651 0 0.00 0 12479 171 0 0
sp_pay_from_customer 6924 6924 0 0.00 5 13098 197 0 0
sp_pay_to_supplier 5660 5660 0 0.00 7 18607 221 0 0
sp_payment_common 12583 12583 0 0.00 2 13077 54 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 1 15064 370 0 0
sp_qd_handle_on_invoice_upd_sts 10319 10319 0 0.00 0 24612 540 0 0
sp_qd_handle_on_reserve_upd_sts 9793 9793 0 0.00 0 20233 77 0 0
sp_reserve_write_off 9795 9795 0 0.00 9 25932 335 0 0
sp_supplier_invoice 5362 5362 0 0.00 9 86758 3248 0 0
sp_supplier_order 5400 5400 0 0.00 7 28258 1411 0 0
srv_aggregate_perf_data 93 93 0 0.00 2574 233838 21661 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 733 164178 35858 0 0
srv_find_qd_qs_mism 1018289 1018289 0 0.00 0 22196 11 0 0
srv_make_invnt_saldo 436 436 0 0.00 112 83610 4846 0 0
srv_make_money_saldo 919 919 0 0.00 1 20883 445 0 0
srv_recalc_idx_stat 5 5 0 0.00 153349 458688 290329 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4927 4927 0 0.00 1 11929 1001 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 2 5864 550 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 4949 34 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2176 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 527 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 999 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 4024 57 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 102345 8040 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 1200 20 0 0
t$perf-norm:sp_client_order 19652 19652 0 0.00 0 5309 39 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 3981 24 0 0
t$perf-norm:sp_pay_from_customer 6924 6924 0 0.00 0 6515 23 0 0
t$perf-norm:sp_pay_to_supplier 5660 5660 0 0.00 0 6245 22 0 0
t$perf-norm:sp_reserve_write_off 9795 9795 0 0.00 0 10295 27 0 0
t$perf-norm:sp_supplier_invoice 5362 5362 0 0.00 0 3740 36 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 3017 35 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1039 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 101 6 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 2189 25 0 0
t$perf-norm:srv_make_money_saldo 919 919 0 0.00 0 911 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 24 209 81 0 0
v_add_invoice_to_stock 10016 9742 274 2.73 0 5915 25 0 274
v_all_customers 36050 36050 0 0.00 0 64 4 0 0
v_all_suppliers 29832 29832 0 0.00 0 86 4 0 0
v_all_wares 39298 39298 0 0.00 0 125 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1582 17 0 0
v_cancel_client_order 3446 3446 0 0.00 0 6800 15 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3575 17 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 6680 19 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 478 10 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 8631 40 0 20
v_cancel_supplier_order 808 808 0 0.00 0 4454 24 0 0
v_cancel_write_off 1912 1912 0 0.00 0 5045 27 0 0
v_max_id_clo_ord 5396 5396 0 0.00 0 3731 6 0 0
v_max_id_clo_res 982 982 0 0.00 0 1493 13 0 0
v_max_id_ord_sup 5351 5351 0 0.00 0 4242 7 0 0
v_max_non_paid_invoice 1488 1488 0 0.00 0 2966 11 0 0
v_max_non_paid_realizn 3556 3556 0 0.00 0 1970 8 0 0
v_min_id_clo_ord 5395 5395 0 0.00 0 3140 12 0 0
v_min_id_clo_res 982 982 0 0.00 0 3550 14 0 0
v_min_id_ord_sup 5351 5351 0 0.00 0 7474 13 0 0
v_min_non_paid_invoice 5659 5659 0 0.00 0 6422 20 0 0
v_min_non_paid_realizn 6924 6924 0 0.00 0 13065 25 0 0
v_our_firm 9984 9984 0 0.00 0 57 5 0 0
v_reserve_write_off 19584 19584 0 0.00 0 6857 13 0 0
x_lock_depdocs_on_canc_invoice 11091 11091 0 0.00 0 23631 140 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 26471 505 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 3 28221 1215 0 0
x_make_qstorno_1200_2000 5362 5362 0 0.00 4 80896 3031 0 0
x_make_qstorno_2100_3300 456193 456193 0 0.00 0 63100 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-04-2022 04:39:25 to 09-04-2022 04:39:29.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:17 75 8192000000 8816400352 8834572288 10779776 0.131 5 2 2 0 10886656 71104 35776
02:08:18 23585 8192000000 9710066880 10007179264 789786448 8.793 100 90 179 1 819673728 7670064 22217216
02:09:47 26603 8192000000 9764336720 10073444352 836201280 9.262 100 86 172 1 868856128 12298256 20356592
02:11:20 13330 8192000000 9805394496 10124304384 870682704 9.607 100 86 172 0 914857920 24738064 19437152
02:12:37 14051 8192000000 9853855152 10185465856 911117472 10.008 100 83 166 0 969806320 40632608 18056240
02:13:59 8754 8192000000 9924013824 10266804224 953600800 10.426 100 86 168 2 1030990160 59531168 17858192
02:15:10 3825 8192000000 9951303024 10303877120 992339904 10.804 100 85 167 1 1070019472 60951264 16728304
02:16:16 2242 8192000000 9993866688 10352664576 1015825536 11.032 100 89 178 1 1110861600 77020640 18015424
02:17:21 1494 8192000000 10006702608 10373566464 1032897152 11.196 100 95 183 1 1129019456 77294736 18827568
02:18:24 1005 8192000000 10034603152 10408644608 1052186736 11.382 100 94 185 0 1150431104 79774512 18469856
02:19:27 1508 8192000000 10025691280 10403659776 1061897520 11.475 100 95 178 1 1152915888 71305968 19712400
02:20:30 909 8192000000 10052099648 10431135744 1065084624 11.505 100 96 189 2 1168824736 84810080 18930032
02:21:32 1295 8192000000 10067026896 10449698816 1076352400 11.613 100 94 184 2 1191970688 96715248 18903040
02:22:35 1221 8192000000 10036807008 10419384320 1083138496 11.677 100 96 181 1 1155801552 54536704 18126352
02:23:37 1161 8192000000 10063735664 10455646208 1081039952 11.657 100 98 186 0 1180403648 80884368 18479328
02:24:40 1090 8192000000 10045080432 10431397888 1085937056 11.704 100 92 181 1 1167958080 63699888 18321136
02:25:42 1270 8192000000 10063919808 10456489984 1091481280 11.757 100 95 173 0 1184530000 74825520 18223200
02:26:44 1304 8192000000 10055190176 10441195520 1095584944 11.796 100 96 178 0 1175777824 61831824 18361056
02:27:47 1219 8192000000 10066998016 10454593536 1093876880 11.780 100 95 185 3 1189063920 76111360 19075680
02:28:49 2884 8192000000 10042413968 10436612096 1082401232 11.670 100 65 127 4 1158230624 62235952 13593440
02:29:54 889 8192000000 10039373888 10426626048 1077431456 11.623 100 92 172 1 1160996320 64359104 19205760
02:30:56 1605 8192000000 10032371328 10412838912 1064336608 11.498 100 94 178 3 1152879584 70824896 17718080
02:31:58 1267 8192000000 10048405616 10430382080 1072728656 11.578 100 95 183 1 1171832592 79944800 19159136
02:33:00 1219 8192000000 10032105120 10409295872 1054831488 11.407 99 89 173 1 1146765888 73709056 18225344
02:34:03 1058 8192000000 9996757632 10372530176 1044996080 11.313 100 89 171 2 1119210144 56469760 17744304
02:35:05 1109 8192000000 10009943952 10391445504 1048163824 11.343 100 79 149 2 1134167248 68954848 17048576
02:36:07 2337 8192000000 9976208592 10353709056 1021290464 11.084 100 65 122 2 1096217936 62617440 12310032
02:37:12 1195 8192000000 9990537776 10365042688 1036470096 11.231 100 88 174 0 1109317360 55665760 17181504
02:38:22 33414 8192000000 9999048944 10383044608 1047418928 11.336 99 64 121 0 1118606704 59212032 11975744
02:40:05 2258 8192000000 10035580656 10421751808 1069177232 11.544 100 76 144 1 1152862512 68811840 14873440
02:41:11 3027 8192000000 10053721792 10438598656 1079807344 11.646 100 73 142 0 1173703664 79073888 14822432
02:42:26 1897 8192000000 10110669168 10495217664 1099776320 11.836 100 87 172 3 1230005376 112003728 18225328
02:43:32 2654 8192000000 10097676800 10489212928 1109252176 11.925 100 86 164 2 1214665168 89032704 16380288
02:44:39 9019 8192000000 10126948864 10521653248 1118677376 12.014 100 71 137 1 1244791360 110853952 15260032
02:45:56 7113 8192000000 10164873952 10568830976 1117761632 12.006 99 82 159 1 1274486512 138712560 18012320
02:47:14 3309 8192000000 10219149536 10619588608 1144420560 12.257 100 79 153 1 1336238848 174630736 17187552
02:48:21 1449 8192000000 10261729168 10661560320 1162326592 12.425 100 90 178 1 1379124544 196983616 19814336
02:49:32 1501 8192000000 10294547152 10700382208 1172706704 12.522 100 84 162 2 1407344240 217096160 17541376
02:50:38 785 8192000000 10324023504 10749718528 1186864896 12.654 100 97 188 0 1437597136 229394176 21338064
02:51:42 1584 8192000000 10325901552 10752090112 1193921536 12.720 100 92 180 0 1438698512 225134176 19642800
02:52:45 826 8192000000 10355117440 10760253440 1192961488 12.711 100 99 193 0 1461380656 246652144 21767024
02:53:51 1614 8192000000 10382028816 10787119104 1196870064 12.747 100 92 170 2 1489235152 272055936 20309152
02:54:58 720 8192000000 10414952640 10840420352 1197090976 12.749 100 95 180 0 1523686720 304716608 21879136
02:56:02 2533 8192000000 10451905904 10862153728 1202199120 12.797 100 92 178 1 1562668016 340064208 20404688
02:57:09 1516 8192000000 10466837616 10899984384 1197443824 12.753 100 90 177 0 1576159264 357686336 21029104
02:58:14 1755 8192000000 10497219600 10904629248 1194185872 12.722 100 93 181 0 1600503168 383960096 22357200
02:59:18 968 8192000000 10531186272 10955194368 1185538336 12.642 100 96 191 0 1637061472 428441152 23081984
03:00:24 2341 8192000000 10526571376 10997248000 1194248864 12.723 100 93 182 0 1629950384 413309728 22391792
03:01:29 809 8192000000 10519355344 10987343872 1191536768 12.698 100 95 185 0 1622924304 408515184 22872352
03:02:33 8097 8192000000 10490277456 10984267776 1195736096 12.737 100 62 117 0 1611376896 399577904 16062896
03:03:53 8357 8192000000 10493199520 10936852480 1172472096 12.520 98 49 96 0 1577042816 392423936 12146784
03:05:11 8565 8192000000 10453788240 10985156608 1180419808 12.594 100 44 89 0 1570281984 378007568 11854608
03:06:22 7118 8192000000 10399456608 10868994048 1179656832 12.587 100 49 93 1 1499687664 308328928 11701904
03:07:32 6965 8192000000 10315368720 10815709184 1167548976 12.474 100 54 108 0 1419018320 238538288 12931056
03:08:41 10749 8192000000 10296250640 10775474176 1149022800 12.300 99 30 60 0 1411403072 254540832 7839440
03:09:56 13753 8192000000 10167946160 10672496640 1131579120 12.136 99 35 61 4 1307528624 168659872 7289632
03:11:12 20248 8192000000 10149200320 10598092800 1141204368 12.227 100 49 96 2 1247753408 96620784 9928256
03:12:37 23361 8192000000 10153110960 10594164736 1149032192 12.300 100 27 52 2 1256861952 101609104 6220656
03:14:01 54310 8192000000 10176550784 10685632512 1143521216 12.249 100 9 18 0 1311680048 165867792 2291040
03:16:52 76997 8192000000 10097143888 10540204032 1107787760 11.911 97 15 28 2 1170030112 59079696 3162656
03:19:10 82827 8192000000 10093411648 10511806464 1098985392 11.828 96 33 64 3 1129780800 23950336 6845072
03:21:33 71361 8192000000 10097345440 10537091072 1113863984 11.969 98 18 35 2 1183595824 66228784 3503056
03:23:46 164173 8192000000 10059577552 10513289216 1083216144 11.678 95 11 20 2 1121231648 35761968 2253536
03:27:32 64339 8192000000 10126058048 10561675264 1131844016 12.139 100 47 91 1 1208505664 67298224 9363424
03:29:42 31339 8192000000 10116621216 10553876480 1140034288 12.216 100 62 113 3 1210643040 57315760 13292992
03:31:16 69671 8192000000 10097784560 10545967104 1133933264 12.158 99 27 54 0 1189648224 49729184 5985776
03:33:28 19286 8192000000 10115153536 10553618432 1116464592 11.994 98 44 85 1 1196376816 71384512 8527712
03:34:53 24334 8192000000 10139763200 10559393792 1143768032 12.251 100 65 118 1 1231126240 74090976 13267232
03:36:22 59197 8192000000 10171115344 10631491584 1115838688 11.988 97 26 52 2 1247826304 125809472 6178144
03:38:42 95142 8192000000 10223377344 10663329792 1099376416 11.832 96 25 50 1 1247249376 141630672 6242288
03:41:21 80107 8192000000 10180586720 10651475968 1131156528 12.132 100 26 52 0 1324206496 185533216 7516752
03:44:11 51943 8192000000 10113654832 10570055680 1138588160 12.202 100 27 57 0 1211056048 66543504 5924384
03:46:04 27030 8192000000 10070518576 10499682304 1115451072 11.984 98 35 70 1 1148069040 25039072 7578896
03:47:34 68844 8192000000 10114442832 10555273216 1112931120 11.960 98 37 73 2 1185964624 65397504 7636000
03:49:46 52647 8192000000 10152333856 10594615296 1115368688 11.983 98 10 20 0 1192877808 75952128 1556992
03:51:39 99917 8192000000 10065359392 10496143360 1091683952 11.759 96 13 26 0 1196834768 102296800 2854016
03:54:21 9107 8192000000 10117127328 10533744640 1138011120 12.197 100 55 108 1 1204205840 55411856 10782864
03:55:35 37871 8192000000 10176212976 10609446912 1151560752 12.324 100 40 80 0 1269615744 109848816 8206176
03:57:18 90541 8192000000 10244398400 10704449536 1151797440 12.326 100 32 64 1 1342146560 183080512 7268608
03:59:49 76049 8192000000 10084760288 10595774464 1144782976 12.261 100 19 37 0 1183787472 35389856 3614640
04:02:08 26464 8192000000 10100640256 10538815488 1140588480 12.221 100 45 89 0 1210220784 60699984 8932320
04:04:04 88410 8192000000 10073615232 10543632384 1130193344 12.123 99 18 36 1 1182398976 48653728 3551904
04:06:44 46943 8192000000 10041715904 10496446464 1079236896 11.640 95 10 20 0 1107624896 26526240 1861760
04:08:31 86622 8192000000 10066544672 10506764288 1095863872 11.798 97 35 67 1 1122528016 20067920 6596224
04:11:52 43327 8192000000 10071664976 10524205056 1135910224 12.177 100 20 40 0 1186498384 46272336 4315824
04:13:40 70592 8192000000 10093531712 10539622400 1091012464 11.752 96 22 44 0 1158948768 63138480 4797824
04:16:11 61773 8192000000 10059118464 10522943488 1101105088 11.848 98 12 24 0 1145351328 41596016 2650224
04:18:17 12260 8192000000 10070286224 10505355264 1122289632 12.049 100 47 94 1 1174096192 42117056 9689504
04:19:31 18235 8192000000 10138773520 10561937408 1121753600 12.044 99 54 107 2 1215563616 83097504 10712512
04:20:50 45286 8192000000 10139070016 10614546432 1139041760 12.207 100 25 50 0 1265580800 121350576 5188464
04:22:36 82759 8192000000 10139058816 10584592384 1077495280 11.624 95 24 48 2 1176090400 93610928 4984192
04:24:59 41272 8192000000 10067760768 10516459520 1118263792 12.011 99 42 79 1 1191081632 64738832 8079008
04:26:46 12567 8192000000 10141941632 10565951488 1108805408 11.921 98 44 86 1 1212426880 94715888 8905584
04:28:04 47581 8192000000 10068294400 10520498176 1097201376 11.811 98 23 46 1 1164182368 61435888 5545104
04:29:53 75402 8192000000 10047640912 10470043648 1091166016 11.754 98 32 58 2 1127115184 29476992 6472176
04:32:19 13271 8192000000 10123214560 10550534144 1130972560 12.131 100 61 120 0 1222016656 79009904 12034192
04:33:37 53095 8192000000 10053824000 10524659712 1108344432 11.917 98 7 14 1 1165498112 55571280 1582400
04:35:30 88478 8192000000 10090346736 10551648256 1102201648 11.859 98 22 44 1 1160244736 53263056 4780032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-04-2022 04:39:31 to 09-04-2022 04:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-04-2022 04:39:48 to 09-04-2022 04:39:50.

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          214877
MON$OLDEST_ACTIVE               217137
MON$OLDEST_SNAPSHOT             217137
MON$NEXT_TRANSACTION            217137
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                       2071776
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Apr  9 04:37:15 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Apr  9 04:37:34 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Apr  9 04:37:36 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sat Apr  9 04:37:38 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

09.04.22 04:39:51. 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-04-09 04:39:54 No severe PSQL-related problems occured
09.04.22 04:39:55. End of report.