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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15134296 kB
MemAvailable 30463348 kB
Buffers 0 kB
Cached 16598280 kB
SwapCached 4256 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-30 10:37:25
DTS_END                         2023-01-30 10:37:25.9680
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/45968
TRN_ID                          226520
ATT_ID                          662
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: 9211.25 22049 1105350
customer order: creation 175.79 444 21095
customer order: refuse 15.37 398 1844
order to supplier: creation 47.79 1371 5735
order to supplier: removal 3.44 134448 413
invoice (draft): creation 45.47 3152 5456
invoice (draft): removal 52.25 857 6270
invoice accept: apply 42.82 24903 5138
invoice accept: cancel 47.23 1063 5668
customer reserve: creation 3903.78 242 468454
customer reserve: removal 4574.22 79 548906
realization accept: apply 84.69 284 10163
realization accept: cancel 86.31 153 10357
payment from customer: creation 59.53 178 7144
payment from customer: removal 5.72 149 686
payment to supplier: creation 50.44 201 6053
payment to supplier: removal 3.47 176 416
service: total inventory turnovers 4.28 3572 513
service: total monetary turnovers 7.59 378 911
service: aggregate perf. data 1.03 13497 123
service: refresh index statistics 0.04 255439 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-01-2023 10:40:10 to 30-01-2023 10:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6876
TEST_TIME 2 5807
TEST_TIME 3 5179
TEST_TIME 4 5173
TEST_TIME 5 4935
TEST_TIME 6 5495
TEST_TIME 7 5533
TEST_TIME 8 5407
TEST_TIME 9 5663
TEST_TIME 10 5722
TEST_TIME 11 5750
TEST_TIME 12 5933
TEST_TIME 13 6149
TEST_TIME 14 6214
TEST_TIME 15 6268
TEST_TIME 16 6637
TEST_TIME 17 7073
TEST_TIME 18 7338
TEST_TIME 19 7688
TEST_TIME 20 8141
TEST_TIME 21 8053
TEST_TIME 22 8171
TEST_TIME 23 8102
TEST_TIME 24 8592
TEST_TIME 25 8644
TEST_TIME 26 9187
TEST_TIME 27 9079
TEST_TIME 28 9502
TEST_TIME 29 9889
TEST_TIME 30 10269
TEST_TIME 31 10533
TEST_TIME 32 10435
TEST_TIME 33 10343
TEST_TIME 34 10368
TEST_TIME 35 10606
TEST_TIME 36 10580
TEST_TIME 37 10386
TEST_TIME 38 10294
TEST_TIME 39 10326
TEST_TIME 40 10320
TEST_TIME 41 10322
TEST_TIME 42 10308
TEST_TIME 43 10252
TEST_TIME 44 10336
TEST_TIME 45 10285
TEST_TIME 46 10214
TEST_TIME 47 10197
TEST_TIME 48 10058
TEST_TIME 49 10046
TEST_TIME 50 9938
TEST_TIME 51 9921
TEST_TIME 52 9774
TEST_TIME 53 9708
TEST_TIME 54 9701
TEST_TIME 55 9651
TEST_TIME 56 9645
TEST_TIME 57 9690
TEST_TIME 58 9710
TEST_TIME 59 9685
TEST_TIME 60 9588
TEST_TIME 61 9518
TEST_TIME 62 9508
TEST_TIME 63 9455
TEST_TIME 64 9518
TEST_TIME 65 9527
TEST_TIME 66 9528
TEST_TIME 67 9601
TEST_TIME 68 9611
TEST_TIME 69 9648
TEST_TIME 70 9640
TEST_TIME 71 9666
TEST_TIME 72 9684
TEST_TIME 73 9713
TEST_TIME 74 9711
TEST_TIME 75 9711
TEST_TIME 76 9604
TEST_TIME 77 9605
TEST_TIME 78 9603
TEST_TIME 79 9537
TEST_TIME 80 9497
TEST_TIME 81 9526
TEST_TIME 82 9464
TEST_TIME 83 9435
TEST_TIME 84 9396
TEST_TIME 85 9310
TEST_TIME 86 9305
TEST_TIME 87 9330
TEST_TIME 88 9305
TEST_TIME 89 9282
TEST_TIME 90 9298
TEST_TIME 91 9285
TEST_TIME 92 9283
TEST_TIME 93 9358
TEST_TIME 94 9357
TEST_TIME 95 9407
TEST_TIME 96 9469
TEST_TIME 97 9461
TEST_TIME 98 9489
TEST_TIME 99 9498
TEST_TIME 100 9513
TEST_TIME 101 9494
TEST_TIME 102 9491
TEST_TIME 103 9455
TEST_TIME 104 9440
TEST_TIME 105 9373
TEST_TIME 106 9327
TEST_TIME 107 9335
TEST_TIME 108 9315
TEST_TIME 109 9311
TEST_TIME 110 9273
TEST_TIME 111 9248
TEST_TIME 112 9237
TEST_TIME 113 9273
TEST_TIME 114 9274
TEST_TIME 115 9271
TEST_TIME 116 9263
TEST_TIME 117 9282
TEST_TIME 118 9260
TEST_TIME 119 9276
TEST_TIME 120 9240

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-01-2023 10:40:14 to 30-01-2023 10:40:18.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1537582 1537582 0 0.00 0 29909 13 0 0
doc_list_biud 1537575 1537575 0 0.00 0 39805 34 0 0
sp_add_invoice_to_stock 5231 5138 93 1.77 57 248493 24904 0 93
sp_cancel_adding_invoice 5668 5668 0 0.00 4 87118 1063 0 0
sp_cancel_client_order 1844 1844 0 0.00 5 11420 399 0 0
sp_cancel_customer_reserve 548906 548906 0 0.00 1 24132 79 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 7353 150 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 13251 177 0 0
sp_cancel_supplier_invoice 6276 6270 6 0.09 2 39889 858 0 6
sp_cancel_supplier_order 413 413 0 0.00 12 1374033 134448 0 0
sp_cancel_write_off 10357 10357 0 0.00 2 10516 153 0 0
sp_client_order 21095 21095 0 0.00 5 30016 445 0 0
sp_customer_reserve 468454 468454 0 0.00 0 103225 242 0 0
sp_fill_shopping_cart 32277 32277 0 0.00 1 15017 79 0 0
sp_fill_shopping_cart_clo_res 468429 468429 0 0.00 0 21538 18 0 0
sp_get_clo_for_invoice 5101 5101 0 0.00 0 15101 246 0 0
sp_kill_cost_storno 17127 17127 0 0.00 0 24968 70 0 0
sp_kill_qstorno_ret_qs2qd 1114852 1114852 0 0.00 0 39804 35 0 0
sp_kill_qty_storno 588718 588718 0 0.00 0 39805 82 0 0
sp_lock_selected_doc 21103 21103 0 0.00 0 9654 10 0 0
sp_make_cost_storno 28461 28461 0 0.00 0 13743 78 0 0
sp_multiply_rows_for_pdistr 28459 28459 0 0.00 0 9140 41 0 0
sp_multiply_rows_for_qdistr 21094 21094 0 0.00 0 17116 157 0 0
sp_pay_from_customer 7144 7144 0 0.00 5 15043 178 0 0
sp_pay_to_supplier 6053 6053 0 0.00 6 14622 202 0 0
sp_payment_common 13197 13197 0 0.00 2 8999 47 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 1 11412 314 0 0
sp_qd_handle_on_invoice_upd_sts 10768 10768 0 0.00 0 23904 461 0 0
sp_qd_handle_on_reserve_upd_sts 10160 10160 0 0.00 0 11452 61 0 0
sp_reserve_write_off 10163 10163 0 0.00 9 22014 284 0 0
sp_supplier_invoice 5456 5456 0 0.00 11 98092 3152 0 0
sp_supplier_order 5735 5735 0 0.00 6 119462 1371 0 0
srv_aggregate_perf_data 123 123 0 0.00 2514 125035 13497 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 752 162703 35109 0 0
srv_find_qd_qs_mism 1056005 1056005 0 0.00 0 29905 10 0 0
srv_make_invnt_saldo 513 513 0 0.00 61 54402 3573 0 0
srv_make_money_saldo 911 911 0 0.00 1 11081 378 0 0
srv_recalc_idx_stat 5 5 0 0.00 46011 376420 255439 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5138 5138 0 0.00 1 17805 844 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 2 6475 401 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 17809 38 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 1150 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 441 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 569 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1038 31 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 87116 6039 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 6022 22 0 0
t$perf-norm:sp_client_order 21095 21095 0 0.00 0 10221 34 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 708 13 0 0
t$perf-norm:sp_pay_from_customer 7144 7144 0 0.00 0 18937 23 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 7874 21 0 0
t$perf-norm:sp_reserve_write_off 10163 10163 0 0.00 0 9638 23 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 6052 32 0 0
t$perf-norm:sp_supplier_order 5735 5735 0 0.00 0 6536 29 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 529 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 26 3 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 855 14 0 0
t$perf-norm:srv_make_money_saldo 911 911 0 0.00 0 1779 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 198 85 0 0
v_add_invoice_to_stock 10380 10194 186 1.79 0 10617 30 0 186
v_all_customers 38506 38506 0 0.00 0 64 4 0 0
v_all_suppliers 31148 31148 0 0.00 0 84 4 0 0
v_all_wares 42180 42180 0 0.00 0 109 4 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 1551 15 0 0
v_cancel_client_order 3688 3688 0 0.00 0 4487 12 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2143 14 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 790 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 4211 19 0 0
v_cancel_supplier_invoice 808 796 12 1.48 0 4240 27 0 12
v_cancel_supplier_order 826 826 0 0.00 0 1618 13 0 0
v_cancel_write_off 2016 2016 0 0.00 0 2269 23 0 0
v_max_id_clo_ord 5733 5733 0 0.00 0 664 5 0 0
v_max_id_clo_res 953 953 0 0.00 0 1430 9 0 0
v_max_id_ord_sup 5449 5449 0 0.00 0 6684 9 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 1887 9 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 942 8 0 0
v_min_id_clo_ord 5733 5733 0 0.00 0 1914 8 0 0
v_min_id_clo_res 953 953 0 0.00 0 459 7 0 0
v_min_id_ord_sup 5449 5449 0 0.00 0 6596 10 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 7162 23 0 0
v_min_non_paid_realizn 7144 7144 0 0.00 0 7242 18 0 0
v_our_firm 10618 10618 0 0.00 0 71 4 0 0
v_reserve_write_off 20318 20318 0 0.00 0 6573 13 0 0
x_lock_depdocs_on_canc_invoice 11540 11540 0 0.00 0 11238 102 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 17504 359 0 0
x_make_qstorno_1000_1200 5735 5735 0 0.00 2 119449 1210 0 0
x_make_qstorno_1200_2000 5455 5455 0 0.00 3 94262 2934 0 0
x_make_qstorno_2100_3300 468452 468452 0 0.00 0 103211 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 10:40:19 to 30-01-2023 10:40:23.

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:29 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:30 11778 8192000000 9712438848 10012680192 796513312 8.861 100 86 173 0 824885616 7720032 20652272
08:09:46 16193 8192000000 9759097792 10065969152 833253632 9.232 100 92 182 0 866874464 11541136 22079696
08:11:06 8761 8192000000 9780202912 10092810240 849053040 9.391 100 87 174 0 888183440 18362384 20768016
08:12:23 10191 8192000000 9850791216 10176073728 886544432 9.765 100 86 172 0 950838208 44980256 19313520
08:13:36 6239 8192000000 9896426544 10234286080 927745600 10.172 100 88 174 1 1007661376 60693616 19222160
08:14:47 2475 8192000000 9941315104 10289180672 969199856 10.579 100 86 165 0 1061416240 75432720 16783664
08:15:53 982 8192000000 9971864656 10327130112 1002384864 10.902 100 92 181 0 1090287216 70425888 17476464
08:16:56 1443 8192000000 10001534448 10370064384 1024232960 11.113 100 97 191 0 1123034800 79793760 19008080
08:17:59 1302 8192000000 10001916656 10375688192 1045015904 11.313 100 95 184 1 1123734688 59492256 19226528
08:19:01 928 8192000000 10020944928 10396794880 1054846352 11.407 100 97 185 0 1143016160 69421360 18748448
08:20:04 3672 8192000000 10023835008 10409332736 1058958032 11.447 100 69 123 1 1150067376 78966992 12142352
08:21:09 1025 8192000000 10041026880 10424643584 1075251408 11.602 100 96 184 3 1165246080 71184112 18810560
08:22:11 998 8192000000 10033539936 10418528256 1083240896 11.678 100 89 171 0 1154353408 54192208 16920304
08:23:13 4078 8192000000 10034089280 10428014592 1081869024 11.665 100 67 130 0 1165946720 70891712 13185984
08:24:18 1097 8192000000 10047439280 10439389184 1085456800 11.699 100 85 164 3 1172443952 69455504 17531648
08:25:21 1159 8192000000 10065128704 10453934080 1089730016 11.740 100 95 181 1 1185370032 77550736 18089280
08:26:23 1863 8192000000 10062352480 10457157632 1094102448 11.782 100 73 138 5 1189356928 81102944 14151536
08:27:26 925 8192000000 10050526048 10442452992 1095604480 11.796 100 86 174 1 1171558144 57780720 18172944
08:28:27 3951 8192000000 10038160992 10426712064 1081444208 11.661 99 68 128 3 1148714960 54635552 12635200
08:29:34 936 8192000000 10037497008 10419007488 1066166912 11.515 99 92 177 4 1158256656 73453760 18635984
08:30:35 1065 8192000000 10037692928 10424332288 1076168256 11.611 100 87 164 5 1159474112 67389616 15916240
08:31:37 868 8192000000 10008685392 10385506304 1058247856 11.440 100 92 174 0 1130796608 54459984 18088768
08:32:39 971 8192000000 10008975440 10384637952 1053426528 11.394 100 96 180 1 1127879456 55910864 18542064
08:33:40 1203 8192000000 9984425024 10361061376 1036502240 11.231 100 89 170 2 1106874656 51499632 18872784
08:34:43 1057 8192000000 9995373952 10371891200 1040519984 11.270 100 96 180 1 1116516192 57240656 18755552
08:35:45 2531 8192000000 9986955840 10369523712 1037110864 11.237 100 66 125 2 1109566272 59944128 12511280
08:36:49 2329 8192000000 9967021856 10341838848 1028612000 11.155 99 74 133 0 1091033568 48760384 13661184
08:37:58 25438 8192000000 10011660640 10400473088 1066530816 11.519 100 67 130 1 1144028976 63987952 13510208
08:39:30 7414 8192000000 10047854496 10434916352 1083782288 11.683 100 64 122 0 1158489120 62565648 12141184
08:40:40 7640 8192000000 10076356160 10465435648 1096026784 11.800 100 71 138 1 1191078176 81264944 13786448
08:41:55 14564 8192000000 10112652672 10509848576 1115098000 11.981 100 68 126 1 1229137344 100282448 13756896
08:43:12 725 8192000000 10186224096 10574381056 1132029248 12.140 100 92 181 1 1301728096 150554016 19144832
08:44:16 1203 8192000000 10196870896 10593206272 1143785056 12.251 100 80 150 0 1317716336 157717680 16213600
08:45:22 2715 8192000000 10221356960 10614296576 1144362784 12.257 99 89 169 1 1338357968 175667648 18327536
08:46:28 1795 8192000000 10272961040 10675658752 1153046432 12.338 100 87 168 3 1382288352 210812112 18429808
08:47:33 1172 8192000000 10283925472 10687725568 1161794432 12.420 100 85 166 1 1400084672 219568576 18721664
08:48:37 1274 8192000000 10322477104 10720006144 1159808032 12.401 99 95 187 0 1434988624 254053424 21127168
08:49:41 774 8192000000 10377923712 10783969280 1172812720 12.523 100 88 174 0 1488467616 295096512 20558384
08:50:45 959 8192000000 10385970128 10791284736 1175239264 12.546 100 95 187 0 1499708560 303110608 21358688
08:51:50 992 8192000000 10426704256 10833276928 1181344032 12.603 100 97 191 0 1538599696 334486448 22769216
08:52:55 1113 8192000000 10434930352 10860978176 1176452592 12.557 100 98 191 1 1547877200 348422672 23001936
08:53:58 1821 8192000000 10401455072 10847477760 1171704032 12.513 100 92 172 0 1510533440 318864672 19964736
08:55:02 1755 8192000000 10413874736 10863587328 1182071760 12.610 100 94 177 2 1535517216 332213072 21232384
08:56:07 784 8192000000 10422139472 10859323392 1190380000 12.687 100 100 199 0 1529637648 315701232 23556416
08:57:10 1082 8192000000 10419260576 10845487104 1179473808 12.585 100 93 185 1 1524277808 323978544 20825456
08:58:12 4530 8192000000 10411479056 10839142400 1180894736 12.599 100 58 114 0 1510793520 316134576 13764208
08:59:19 4284 8192000000 10429520224 10873028608 1161985344 12.422 99 57 108 0 1537213968 362670768 12557856
09:00:32 1234 8192000000 10440530384 10860703744 1153604704 12.343 99 80 157 0 1543541824 371938064 17999056
09:01:44 11266 8192000000 10404125680 10836443136 1172605872 12.521 100 40 78 1 1512940672 330723232 9611568
09:02:58 8272 8192000000 10394531952 10872537088 1163843696 12.439 100 43 86 2 1504812512 330465824 10502992
09:04:10 16972 8192000000 10349979792 10805788672 1138417680 12.201 100 25 50 0 1451055504 305723312 6914512
09:05:28 14259 8192000000 10257416064 10811478016 1138415568 12.201 100 32 62 0 1353646976 207280896 7950512
09:06:43 16248 8192000000 10225741296 10681249792 1139230752 12.208 100 41 77 1 1331163200 182630432 9302016
09:08:02 8080 8192000000 10162409072 10635640832 1148744272 12.298 100 45 88 0 1286536576 127910720 9881584
09:09:17 39776 8192000000 10128874528 10555699200 1133207200 12.152 99 38 72 0 1235075216 94475152 7392864
09:11:10 37363 8192000000 10199707632 10664796160 1130764464 12.129 99 13 26 0 1298261360 164317536 3179360
09:12:48 63006 8192000000 10058635872 10484441088 1120898128 12.035 100 28 56 0 1162036064 35079808 6058128
09:14:55 55166 8192000000 10100422176 10539347968 1136053488 12.178 100 48 92 0 1223019472 77409072 9556912
09:17:53 107996 8192000000 10082525968 10559586304 1140269648 12.218 100 7 14 0 1228296560 86651040 1375872
09:20:45 93943 8192000000 10080950384 10580221952 1119666064 12.024 98 11 20 0 1167105328 45103968 2335296
09:23:20 80333 8192000000 10119771808 10603872256 1132160480 12.142 98 15 30 0 1183858128 48637856 3059792
09:25:42 122053 8192000000 10073507504 10524450816 1092603616 11.767 96 16 28 2 1127304688 31506032 3195040
09:28:47 65348 8192000000 10083236368 10515398656 1129164944 12.113 100 34 68 0 1174708224 38555440 6987840
09:31:26 19328 8192000000 10086916112 10515759104 1109376928 11.927 97 36 70 1 1160214352 42549920 8287504
09:32:47 5706 8192000000 10137843584 10557136896 1146658736 12.278 100 83 161 4 1238940112 72555088 19726288
09:33:54 8596 8192000000 10156919648 10602577920 1149206544 12.302 100 61 103 2 1257650064 97070704 11372816
09:35:04 162703 8192000000 10077353232 10549149696 1079906112 11.647 95 12 25 0 1156842304 74281536 2654656
09:38:47 22772 8192000000 10081664016 10561445888 1114731968 11.977 98 29 54 0 1156356912 36442688 5182256
09:40:55 56722 8192000000 10082022592 10528161792 1094824400 11.789 96 26 51 0 1169237200 69237104 5175696
09:42:53 20052 8192000000 10116114736 10549350400 1128669936 12.109 99 33 67 0 1200401984 63722592 8009456
09:44:17 53352 8192000000 10061888656 10529488896 1102799312 11.864 97 18 32 1 1156121152 50338912 2982928
09:46:21 27469 8192000000 10159925360 10594074624 1144200384 12.255 100 40 80 0 1258549152 105869392 8479376
09:47:49 66847 8192000000 10105040416 10543378432 1098410544 11.823 96 16 29 0 1172483744 71042128 3031072
09:49:59 122872 8192000000 10122855280 10566070272 1103242864 11.868 96 19 38 0 1165592848 58519920 3830064
09:53:38 75413 8192000000 10092904672 10595004416 1130181728 12.123 100 30 59 0 1210217152 73579648 6455776
09:56:04 34193 8192000000 10109245776 10551463936 1133386128 12.153 100 33 65 1 1195390672 55021184 6983360
09:57:39 56067 8192000000 10104216736 10586497024 1137359456 12.191 100 19 38 1 1232809568 91484576 3965536
09:59:45 47851 8192000000 10090638944 10542407680 1121336672 12.040 99 24 48 0 1179968880 53590944 5041264
10:01:43 72763 8192000000 10095592288 10576310272 1112201536 11.953 98 11 22 0 1184396288 70057872 2136880
10:04:26 50332 8192000000 10070234432 10561245184 1123239232 12.058 99 12 24 0 1162024000 35889264 2895504
10:06:17 4757 8192000000 10074738160 10519158784 1133443152 12.154 100 82 143 8 1172984592 22873280 16668160
10:07:23 20753 8192000000 10133137872 10593701888 1149887488 12.308 100 44 88 0 1244654864 85833840 8933536
10:08:47 63083 8192000000 10134967520 10593669120 1117139440 12.000 98 36 72 0 1206552672 82440784 6972448
10:10:56 63084 8192000000 10150488960 10631229440 1136174112 12.180 98 20 40 0 1234614720 94217920 4222688
10:13:24 29404 8192000000 10070545376 10496618496 1117262064 12.001 98 42 81 0 1150604464 24442592 8899808
10:14:54 19486 8192000000 10136470144 10588987392 1130462608 12.126 99 43 87 0 1217905216 78276144 9166464
10:16:14 23612 8192000000 10101859552 10562150400 1128898960 12.111 99 37 74 0 1189692928 53647600 7146368
10:17:43 70028 8192000000 10112005888 10575106048 1109380256 11.927 97 30 60 0 1178430656 63116240 5934160
10:20:51 1374 8192000000 10062304784 10564734976 1129586320 12.117 100 28 54 1 1168653520 33351184 5716016
10:21:55 63527 8192000000 10076289152 10550464512 1106612368 11.900 98 11 22 1 1161320720 52140672 2567680
10:23:59 61280 8192000000 10078020080 10560847872 1127621216 12.099 100 21 42 0 1203988864 71858416 4509232
10:26:00 78191 8192000000 10063900288 10536214528 1091235408 11.754 96 13 26 0 1141285168 47249392 2800368
10:28:21 45856 8192000000 10107344928 10562273280 1101672848 11.854 97 47 93 1 1181585968 69860384 10052736
10:30:09 72645 8192000000 10064118960 10531119104 1087355168 11.718 97 16 29 2 1158934976 67355328 4224480
10:32:23 13249 8192000000 10109042080 10538934272 1125163360 12.076 100 52 104 0 1205096320 69645360 10287600
10:33:38 86045 8192000000 10081003712 10528563200 1105491936 11.890 98 35 70 0 1170403584 58209152 6702496
10:36:05 17574 8192000000 10112704320 10534707200 1128744064 12.110 100 58 116 1 1196766512 56671920 11350528
10:37:24 32096 8192000000 9636072736 10061852672 714807520 8.025 65 5 10 0 770931680 55472736 651424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-01-2023 10:40:25 to 30-01-2023 10:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2023 10:40:42 to 30-01-2023 10:40:44.

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          226011
MON$OLDEST_ACTIVE               226707
MON$OLDEST_SNAPSHOT             226707
MON$NEXT_TRANSACTION            226707
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                       2057440
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
:191: 
:192: mscs-test-161.budzdorov.ru    Mon Jan 30 10:38:36 2023
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Mon Jan 30 10:38:36 2023
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Mon Jan 30 10:38:36 2023
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:202: 
--- end of diff output ---

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

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