Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.03.2023 10: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 15106360 kB
MemAvailable 30406448 kB
Buffers 0 kB
Cached 16593568 kB
SwapCached 3028 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-06 10:37:28
DTS_END                         2023-03-06 10:37:28.3760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47474
TRN_ID                          223608
ATT_ID                          586
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: 8970.97 25195 1076516
customer order: creation 170.18 464 20421
customer order: refuse 14.13 415 1696
order to supplier: creation 46.24 1362 5549
order to supplier: removal 3.22 165982 386
invoice (draft): creation 45.98 3354 5518
invoice (draft): removal 50.03 997 6004
invoice accept: apply 40.78 25936 4893
invoice accept: cancel 44.78 1239 5373
customer reserve: creation 3867.84 242 464141
customer reserve: removal 4388.20 96 526584
realization accept: apply 84.96 288 10195
realization accept: cancel 85.18 174 10221
payment from customer: creation 58.70 186 7044
payment from customer: removal 5.47 152 656
payment to supplier: creation 49.16 205 5899
payment to supplier: removal 3.25 114 390
service: total inventory turnovers 4.07 3884 488
service: total monetary turnovers 7.77 376 932
service: aggregate perf. data 1.01 14964 121
service: refresh index statistics 0.04 283479 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-03-2023 10:38:49 to 06-03-2023 10:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5531
TEST_TIME 2 5615
TEST_TIME 3 5341
TEST_TIME 4 5093
TEST_TIME 5 5247
TEST_TIME 6 5496
TEST_TIME 7 5605
TEST_TIME 8 5657
TEST_TIME 9 5968
TEST_TIME 10 6211
TEST_TIME 11 6379
TEST_TIME 12 6408
TEST_TIME 13 6677
TEST_TIME 14 6705
TEST_TIME 15 6901
TEST_TIME 16 6907
TEST_TIME 17 6968
TEST_TIME 18 7285
TEST_TIME 19 7338
TEST_TIME 20 7476
TEST_TIME 21 7561
TEST_TIME 22 7766
TEST_TIME 23 7751
TEST_TIME 24 8073
TEST_TIME 25 8157
TEST_TIME 26 8638
TEST_TIME 27 8656
TEST_TIME 28 8744
TEST_TIME 29 9325
TEST_TIME 30 9573
TEST_TIME 31 10371
TEST_TIME 32 10933
TEST_TIME 33 10821
TEST_TIME 34 10630
TEST_TIME 35 10520
TEST_TIME 36 10526
TEST_TIME 37 10450
TEST_TIME 38 10276
TEST_TIME 39 10157
TEST_TIME 40 10125
TEST_TIME 41 10078
TEST_TIME 42 9966
TEST_TIME 43 9946
TEST_TIME 44 9953
TEST_TIME 45 9887
TEST_TIME 46 9881
TEST_TIME 47 9885
TEST_TIME 48 9763
TEST_TIME 49 9707
TEST_TIME 50 9669
TEST_TIME 51 9542
TEST_TIME 52 9559
TEST_TIME 53 9786
TEST_TIME 54 9725
TEST_TIME 55 9641
TEST_TIME 56 9782
TEST_TIME 57 9741
TEST_TIME 58 9825
TEST_TIME 59 9724
TEST_TIME 60 9740
TEST_TIME 61 9793
TEST_TIME 62 9874
TEST_TIME 63 9816
TEST_TIME 64 9870
TEST_TIME 65 9784
TEST_TIME 66 9697
TEST_TIME 67 9631
TEST_TIME 68 9610
TEST_TIME 69 9507
TEST_TIME 70 9527
TEST_TIME 71 9475
TEST_TIME 72 9438
TEST_TIME 73 9440
TEST_TIME 74 9360
TEST_TIME 75 9316
TEST_TIME 76 9324
TEST_TIME 77 9364
TEST_TIME 78 9372
TEST_TIME 79 9473
TEST_TIME 80 9442
TEST_TIME 81 9393
TEST_TIME 82 9476
TEST_TIME 83 9483
TEST_TIME 84 9433
TEST_TIME 85 9340
TEST_TIME 86 9275
TEST_TIME 87 9294
TEST_TIME 88 9213
TEST_TIME 89 9169
TEST_TIME 90 9173
TEST_TIME 91 9098
TEST_TIME 92 9077
TEST_TIME 93 9125
TEST_TIME 94 9157
TEST_TIME 95 9133
TEST_TIME 96 9100
TEST_TIME 97 9109
TEST_TIME 98 9151
TEST_TIME 99 9158
TEST_TIME 100 9216
TEST_TIME 101 9264
TEST_TIME 102 9239
TEST_TIME 103 9335
TEST_TIME 104 9359
TEST_TIME 105 9339
TEST_TIME 106 9333
TEST_TIME 107 9278
TEST_TIME 108 9251
TEST_TIME 109 9210
TEST_TIME 110 9206
TEST_TIME 111 9183
TEST_TIME 112 9133
TEST_TIME 113 9097
TEST_TIME 114 9043
TEST_TIME 115 9026
TEST_TIME 116 9027
TEST_TIME 117 9078
TEST_TIME 118 9053
TEST_TIME 119 9032
TEST_TIME 120 8987

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-03-2023 10:38:53 to 06-03-2023 10:38:56.

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 1503013 1503013 0 0.00 0 16702 13 0 0
doc_list_biud 1503003 1503003 0 0.00 0 25836 39 0 0
sp_add_invoice_to_stock 5070 4893 177 3.49 52 222649 25937 0 177
sp_cancel_adding_invoice 5373 5373 0 0.00 7 92555 1240 0 0
sp_cancel_client_order 1696 1696 0 0.00 6 10904 415 0 0
sp_cancel_customer_reserve 526584 526584 0 0.00 1 25867 96 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 11280 153 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 3007 115 0 0
sp_cancel_supplier_invoice 6018 6004 14 0.23 2 16936 998 0 14
sp_cancel_supplier_order 386 386 0 0.00 10 1322691 165982 0 0
sp_cancel_write_off 10221 10221 0 0.00 2 22101 175 0 0
sp_client_order 20421 20421 0 0.00 5 10412 464 0 0
sp_customer_reserve 464141 464141 0 0.00 0 105807 242 0 0
sp_fill_shopping_cart 31471 31471 0 0.00 1 13897 83 0 0
sp_fill_shopping_cart_clo_res 464115 464115 0 0.00 0 16164 20 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 17493 304 0 0
sp_kill_cost_storno 16640 16640 0 0.00 0 7304 72 0 0
sp_kill_qstorno_ret_qs2qd 1069779 1069779 0 0.00 0 23033 42 0 0
sp_kill_qty_storno 565317 565317 0 0.00 0 25827 97 0 0
sp_lock_selected_doc 20691 20691 0 0.00 0 11267 8 0 0
sp_make_cost_storno 27999 27999 0 0.00 0 13200 83 0 0
sp_multiply_rows_for_pdistr 27998 27998 0 0.00 0 9382 37 0 0
sp_multiply_rows_for_qdistr 20420 20420 0 0.00 0 9635 174 0 0
sp_pay_from_customer 7044 7044 0 0.00 5 22807 187 0 0
sp_pay_to_supplier 5899 5899 0 0.00 7 15802 205 0 0
sp_payment_common 12943 12943 0 0.00 2 9009 45 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 1 10865 341 0 0
sp_qd_handle_on_invoice_upd_sts 10233 10233 0 0.00 0 24192 488 0 0
sp_qd_handle_on_reserve_upd_sts 10193 10193 0 0.00 0 25733 65 0 0
sp_reserve_write_off 10195 10195 0 0.00 8 25818 289 0 0
sp_supplier_invoice 5518 5518 0 0.00 8 113391 3354 0 0
sp_supplier_order 5549 5549 0 0.00 8 32857 1362 0 0
srv_aggregate_perf_data 121 121 0 0.00 2059 120647 14965 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 753 130628 33034 0 0
srv_find_qd_qs_mism 1027181 1027181 0 0.00 0 14159 9 0 0
srv_make_invnt_saldo 488 488 0 0.00 69 69613 3885 0 0
srv_make_money_saldo 932 932 0 0.00 1 36981 376 0 0
srv_recalc_idx_stat 5 5 0 0.00 85518 659721 283479 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4896 4896 0 0.00 1 10216 885 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 10031 541 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 3834 31 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 702 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 1116 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 1537 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1367 25 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 77945 7290 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 5098 30 0 0
t$perf-norm:sp_client_order 20421 20421 0 0.00 0 17618 39 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 695 15 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 7033 21 0 0
t$perf-norm:sp_pay_to_supplier 5899 5899 0 0.00 0 6670 22 0 0
t$perf-norm:sp_reserve_write_off 10195 10195 0 0.00 0 11215 24 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 8919 35 0 0
t$perf-norm:sp_supplier_order 5549 5549 0 0.00 0 4816 33 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 426 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 159 7 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 2941 22 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 5031 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 296 106 0 0
v_add_invoice_to_stock 10064 9710 354 3.51 0 8228 28 0 354
v_all_customers 37174 37174 0 0.00 0 170 4 0 0
v_all_suppliers 30835 30835 0 0.00 0 167 4 0 0
v_all_wares 40832 40832 0 0.00 0 304 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 780 15 0 0
v_cancel_client_order 3392 3392 0 0.00 0 5511 14 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 1817 13 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2846 17 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2912 15 0 0
v_cancel_supplier_invoice 822 794 28 3.40 0 1003 22 0 28
v_cancel_supplier_order 772 772 0 0.00 0 2842 15 0 0
v_cancel_write_off 2012 2012 0 0.00 0 8033 29 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 2319 6 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 2653 16 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 9599 8 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 53 7 0 0
v_max_non_paid_realizn 3707 3707 0 0.00 0 923 7 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 1626 8 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 1035 9 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 5192 15 0 0
v_min_non_paid_invoice 5898 5898 0 0.00 0 8994 19 0 0
v_min_non_paid_realizn 7043 7043 0 0.00 0 5157 16 0 0
v_our_firm 10330 10330 0 0.00 0 172 4 0 0
v_reserve_write_off 20380 20380 0 0.00 0 8886 14 0 0
x_lock_depdocs_on_canc_invoice 10980 10980 0 0.00 0 12039 122 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 10043 348 0 0
x_make_qstorno_1000_1200 5549 5549 0 0.00 2 32839 1203 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 4 109663 3120 0 0
x_make_qstorno_2100_3300 464140 464140 0 0.00 0 105802 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-03-2023 10:38:58 to 06-03-2023 10:39:02.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:32 943 8192000000 8923882672 8980160512 4612400 0.056 3 1 2 0 4710016 61840 35776
08:08:34 25141 8192000000 9733226960 10030665728 804477280 8.942 100 93 184 0 835438064 7786832 23173952
08:10:06 27827 8192000000 9763627840 10072485888 837746256 9.277 100 90 178 1 871633488 12507584 21379648
08:11:36 14084 8192000000 9805911344 10126798848 870265808 9.603 100 88 176 0 920074256 29593008 20215440
08:12:53 7855 8192000000 9857528304 10190073856 917229296 10.069 100 74 148 0 970394112 37150336 16014480
08:14:05 2085 8192000000 9926519760 10272403456 967934352 10.567 100 95 179 2 1038657088 52068752 18653984
08:15:10 1592 8192000000 9967183344 10322731008 1003427968 10.912 100 92 176 3 1085004304 62938752 18637584
08:16:15 5355 8192000000 10004327344 10370650112 1033202640 11.199 100 82 154 1 1122624512 73749968 15671904
08:17:24 1096 8192000000 10027226000 10407227392 1050385200 11.364 100 96 179 0 1150674848 80974208 19315440
08:18:27 1002 8192000000 10036019696 10411323392 1064706832 11.502 100 98 192 0 1154745520 70688416 19350272
08:19:31 1244 8192000000 10034066480 10415222784 1073029344 11.581 100 92 180 1 1161742016 70115744 18596928
08:20:36 944 8192000000 10059746928 10438967296 1084838832 11.694 100 98 179 1 1185132144 81591808 18701504
08:21:39 998 8192000000 10084226912 10470785024 1086568592 11.710 100 93 183 2 1208283712 103387792 18327328
08:22:44 1369 8192000000 10059574352 10449469440 1091317376 11.755 100 94 175 1 1177028448 67798064 17913008
08:23:48 1153 8192000000 10045962896 10433413120 1083517008 11.681 99 95 178 0 1166200768 64191168 18492592
08:24:51 1263 8192000000 10064588192 10451681280 1096840848 11.808 100 99 183 0 1185907168 71783232 17283088
08:25:54 974 8192000000 10071475712 10462175232 1097480512 11.814 100 93 178 1 1192937984 78327344 17130128
08:26:56 1255 8192000000 10081271136 10474168320 1104493408 11.880 100 87 163 1 1198846880 78539280 15814192
08:27:59 1103 8192000000 10072577264 10463449088 1095310960 11.793 99 96 179 1 1193860608 79178320 19371328
08:29:02 1115 8192000000 10048060288 10432741376 1081445760 11.661 99 95 187 2 1169847552 70550864 17850928
08:30:05 1175 8192000000 10044229600 10430025728 1075810128 11.608 99 94 181 3 1169524896 74934832 18779936
08:31:07 3735 8192000000 10024434128 10413662208 1070266960 11.555 100 59 114 2 1142548928 60899536 11382432
08:32:12 1311 8192000000 10023130000 10402975744 1057034048 11.428 99 97 188 3 1147769472 71540672 19194752
08:33:15 771 8192000000 9993329408 10365493248 1039326640 11.258 99 89 166 1 1116424032 58009712 19087680
08:34:16 978 8192000000 10001638528 10377232384 1051572912 11.376 100 92 175 1 1126241344 57248304 17420128
08:35:18 919 8192000000 9999752128 10379935744 1043319888 11.297 100 88 170 1 1122271296 61187248 17764160
08:36:20 908 8192000000 9993884192 10367168512 1037565072 11.241 100 95 182 0 1113201024 57749840 17886112
08:37:22 3186 8192000000 9974364880 10356621312 1047392912 11.336 100 58 108 1 1097374096 39192224 10788960
08:38:34 5023 8192000000 10046540896 10430709760 1071744448 11.569 100 77 150 0 1155203872 69068304 14391120
08:39:45 2011 8192000000 10042725360 10427908096 1076629104 11.615 100 72 142 0 1169415664 78213200 14573360
08:40:52 16175 8192000000 10045717456 10435526656 1079406096 11.642 99 53 105 0 1155826576 65209152 11211328
08:42:14 1117 8192000000 10094747904 10479513600 1102233808 11.859 100 95 177 2 1213667040 92007200 19426032
08:43:19 1634 8192000000 10141754784 10527428608 1114265248 11.973 100 83 157 0 1258434864 127718000 16451616
08:44:28 5342 8192000000 10171927632 10573979648 1123909792 12.064 100 67 125 1 1289093952 151161824 14022336
08:45:59 1690 8192000000 10186495872 10589589504 1135771696 12.176 100 84 164 1 1313143904 159611600 17760608
08:47:06 1160 8192000000 10211306992 10618142720 1149241920 12.302 100 93 180 0 1324340112 155491424 19606768
08:48:12 1298 8192000000 10239845120 10660044800 1162494800 12.427 100 96 186 2 1351844064 168548192 20801072
08:49:20 1077 8192000000 10279627792 10676547584 1169009904 12.488 100 92 179 1 1389053728 200456192 19587632
08:50:24 1434 8192000000 10295205312 10695704576 1173607008 12.531 100 94 184 1 1407471312 213498768 20365536
08:51:30 1224 8192000000 10331673776 10736971776 1181188736 12.601 100 91 164 1 1440429424 240447568 18793120
08:52:35 1212 8192000000 10393619152 10809614336 1183473840 12.623 100 97 194 0 1502247280 295745392 23028048
08:53:40 903 8192000000 10412409168 10810716160 1183411152 12.622 100 95 188 0 1517145120 310378656 23355312
08:54:45 747 8192000000 10419985584 10844532736 1193539392 12.716 100 98 187 2 1531320368 316070560 21710416
08:55:49 1623 8192000000 10473972992 10883944448 1197459968 12.753 100 86 168 0 1578079712 360703200 19916544
08:56:53 1128 8192000000 10482176816 10909851648 1184128624 12.629 100 99 193 3 1588493168 383220304 21144240
08:57:57 1184 8192000000 10533455648 10954113024 1197856880 12.756 100 86 171 0 1640091200 421701808 20532512
08:59:03 1060 8192000000 10525923408 10978246656 1201925872 12.794 100 83 164 0 1630708928 408685328 20097728
09:00:07 1323 8192000000 10548310768 10969083904 1192737600 12.709 100 89 179 0 1650545968 435848752 21959616
09:01:12 4874 8192000000 10538548656 11009040384 1188747648 12.672 100 68 132 1 1647606896 442580416 16278832
09:02:19 2096 8192000000 10532498224 10989617152 1186099184 12.647 100 68 134 1 1636393936 433166736 17128016
09:03:24 14842 8192000000 10511292768 10988691456 1184362304 12.631 100 35 70 0 1615992432 422228208 9401920
09:04:42 16189 8192000000 10513753744 10966511616 1167452912 12.473 100 30 58 1 1612485408 437336352 7696144
09:06:08 13194 8192000000 10390466480 10892378112 1151906464 12.327 100 50 90 1 1509132704 345304192 11922048
09:07:29 11096 8192000000 10218119712 10804396032 1140141184 12.217 99 36 71 1 1369831696 220047104 9643408
09:08:41 25184 8192000000 10199240656 10676015104 1148099104 12.292 99 23 46 0 1310524272 157305408 5119760
09:10:10 21040 8192000000 10061139536 10491174912 1136215488 12.180 100 42 70 2 1162269680 19242528 6811664
09:11:33 16449 8192000000 10108668880 10532716544 1153205872 12.340 100 46 88 1 1207536896 44773200 9557824
09:13:14 12387 8192000000 10105159520 10532433920 1147646224 12.287 99 60 105 3 1205058400 45440656 11971520
09:14:27 130627 8192000000 10112100752 10573127680 1125706288 12.081 98 35 68 0 1190238096 56759072 7772736
09:17:52 61496 8192000000 10087983760 10531921920 1152464976 12.333 100 41 80 2 1208894992 47634336 8795680
09:20:42 19451 8192000000 10122085904 10561916928 1134936768 12.168 98 34 66 0 1217943840 75687856 7319216
09:22:06 13776 8192000000 10116103632 10537447424 1148779824 12.298 100 66 124 1 1207498720 45240688 13478208
09:23:26 5159 8192000000 10088440688 10511769600 1131927408 12.140 99 56 102 4 1185885616 41820608 12137600
09:24:33 46828 8192000000 10067072656 10515746816 1114397104 11.974 98 17 34 1 1160543568 41838352 4308112
09:26:21 34911 8192000000 10119201856 10585645056 1149173664 12.302 100 18 34 0 1226807040 73905392 3727984
09:28:32 104055 8192000000 10157079040 10643795968 1118737664 12.015 97 18 36 1 1249865952 127093632 4034656
09:31:19 64443 8192000000 10115065232 10549858304 1101713136 11.854 96 35 67 0 1168139232 59491600 6934496
09:33:26 54989 8192000000 10103074000 10565447680 1109498448 11.928 97 11 22 0 1220464096 108241712 2723936
09:35:22 46054 8192000000 10081571040 10523439104 1113648736 11.967 98 17 34 0 1176905536 59416944 3839856
09:37:08 42773 8192000000 10166519328 10605166592 1107367504 11.907 97 32 64 0 1215776832 100463824 7945504
09:38:57 68068 8192000000 10144153440 10652610560 1134517888 12.164 99 18 36 0 1270389872 132025136 3846848
09:41:21 38174 8192000000 10108926400 10536189952 1125978336 12.083 98 38 76 0 1176140704 42794416 7367952
09:43:44 43123 8192000000 10067114272 10495868928 1118090016 12.009 98 37 63 0 1143626096 19061856 6474224
09:45:28 57051 8192000000 10064625312 10520502272 1090158784 11.744 96 16 32 0 1150256656 56865072 3232800
09:47:29 51673 8192000000 10042830240 10503880704 1094161120 11.782 97 15 28 1 1129704560 32550576 2992864
09:49:21 22743 8192000000 10060984384 10496778240 1107335232 11.907 98 43 81 0 1148337472 32035616 8966624
09:50:44 32809 8192000000 10098785216 10528260096 1131836960 12.139 99 40 76 3 1181267872 41083840 8347072
09:52:18 59920 8192000000 10153453792 10604949504 1138969856 12.206 100 32 65 0 1249461472 103297600 7194016
09:55:08 70538 8192000000 10164233424 10628771840 1138596736 12.202 99 23 46 0 1224303472 80979808 4726928
09:57:19 21058 8192000000 10060856624 10494988288 1120733744 12.034 99 45 81 0 1157063488 27381120 8948624
09:58:43 44555 8192000000 10130713504 10601193472 1145299776 12.265 100 38 76 0 1226228528 73380528 7548224
10:00:29 123007 8192000000 10078992528 10553712640 1126423152 12.088 98 23 46 0 1178381232 47619168 4338912
10:03:35 1431 8192000000 10082817392 10511642624 1138190752 12.199 100 57 111 3 1182326448 32412784 11722912
10:04:36 40099 8192000000 10067676544 10542448640 1126952704 12.093 99 6 10 0 1181939424 53810640 1176080
10:06:16 60696 8192000000 10088087632 10569748480 1137530640 12.192 100 10 20 0 1204742096 65069248 2142208
10:08:20 73231 8192000000 10159860992 10638147584 1154144176 12.348 100 13 24 0 1264026048 107391008 2490864
10:10:33 76881 8192000000 10116229184 10587947008 1132149376 12.142 98 15 30 0 1204854576 69520240 3184960
10:12:50 37879 8192000000 10149285760 10590703616 1072964032 11.580 93 25 50 0 1173322800 95160368 5198400
10:14:29 31358 8192000000 10193740608 10631327744 1138017168 12.197 99 32 64 0 1270303216 124991248 7294800
10:16:04 42721 8192000000 10169527648 10629279744 1138075056 12.197 99 25 50 0 1263774064 120199408 5499600
10:17:48 35068 8192000000 10059725776 10523516928 1088705680 11.730 96 23 44 0 1159269536 65401584 5162272
10:19:28 52429 8192000000 10130470592 10613338112 1107906736 11.913 98 34 68 0 1221196464 106135264 7154464
10:21:25 110458 8192000000 10088499232 10606493696 1066030352 11.514 94 29 58 0 1146875408 74794176 6050880
10:24:16 92136 8192000000 10066434608 10536980480 1090782256 11.750 97 26 52 0 1138558080 42681760 5094064
10:26:49 50114 8192000000 10087285840 10549252096 1135348336 12.172 100 33 64 0 1202306688 60793728 6164624
10:28:40 43264 8192000000 10071152688 10544685056 1125961856 12.083 99 12 24 0 1167108032 38195184 2950992
10:30:23 64160 8192000000 10107000720 10559877120 1091125392 11.753 97 25 49 0 1167468720 71057104 5286224
10:32:28 72639 8192000000 10099253824 10571018240 1128269168 12.105 100 23 46 0 1215369088 81311792 5788128
10:35:50 22191 8192000000 10060104592 10498887680 1116682256 11.996 99 30 57 0 1155603328 32721792 6199280
10:37:13 28449 8192000000 9089663376 9368379392 237472640 2.817 21 11 22 0 288537264 48760224 2304400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-03-2023 10:39:04 to 06-03-2023 10:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 177 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-03-2023 10:39:21 to 06-03-2023 10:39:23.

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          223889
MON$OLDEST_ACTIVE               223890
MON$OLDEST_SNAPSHOT             223890
MON$NEXT_TRANSACTION            223890
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                       2053920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

06.03.23 10:39:24. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-06 10:39:27 No severe PSQL-related problems occured
06.03.23 10:39:28. End of report.