Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.2023 13: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 14875124 kB
MemAvailable 30451576 kB
Buffers 0 kB
Cached 16829748 kB
SwapCached 3032 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-10 13:37:27
DTS_END                         2023-03-10 13:37:27.2920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54490
TRN_ID                          215271
ATT_ID                          623
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: 9086.22 22175 1090346
customer order: creation 164.88 464 19785
customer order: refuse 13.66 401 1639
order to supplier: creation 44.94 1346 5393
order to supplier: removal 3.55 152440 426
invoice (draft): creation 43.10 3138 5172
invoice (draft): removal 52.68 870 6321
invoice accept: apply 40.47 25304 4856
invoice accept: cancel 46.98 1113 5638
customer reserve: creation 3780.03 239 453603
customer reserve: removal 4611.04 92 553325
realization accept: apply 81.30 265 9756
realization accept: cancel 79.56 161 9547
payment from customer: creation 56.11 178 6733
payment from customer: removal 5.77 103 692
payment to supplier: creation 47.19 184 5663
payment to supplier: removal 3.31 120 397
service: total inventory turnovers 3.67 4207 440
service: total monetary turnovers 7.06 386 847
service: aggregate perf. data 0.89 16819 107
service: refresh index statistics 0.05 235667 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2023 13:38:50 to 10-03-2023 13:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7493
TEST_TIME 2 5556
TEST_TIME 3 4976
TEST_TIME 4 5078
TEST_TIME 5 5522
TEST_TIME 6 5972
TEST_TIME 7 6241
TEST_TIME 8 6147
TEST_TIME 9 6396
TEST_TIME 10 6597
TEST_TIME 11 6799
TEST_TIME 12 7110
TEST_TIME 13 7127
TEST_TIME 14 7162
TEST_TIME 15 7305
TEST_TIME 16 7241
TEST_TIME 17 7916
TEST_TIME 18 7820
TEST_TIME 19 7741
TEST_TIME 20 7675
TEST_TIME 21 8020
TEST_TIME 22 8355
TEST_TIME 23 8420
TEST_TIME 24 8765
TEST_TIME 25 8951
TEST_TIME 26 8837
TEST_TIME 27 9203
TEST_TIME 28 9328
TEST_TIME 29 9402
TEST_TIME 30 9897
TEST_TIME 31 10323
TEST_TIME 32 10783
TEST_TIME 33 10757
TEST_TIME 34 10738
TEST_TIME 35 10674
TEST_TIME 36 10450
TEST_TIME 37 10433
TEST_TIME 38 10304
TEST_TIME 39 10153
TEST_TIME 40 10004
TEST_TIME 41 9953
TEST_TIME 42 10001
TEST_TIME 43 10074
TEST_TIME 44 9950
TEST_TIME 45 9942
TEST_TIME 46 9879
TEST_TIME 47 10043
TEST_TIME 48 9916
TEST_TIME 49 9946
TEST_TIME 50 9907
TEST_TIME 51 9888
TEST_TIME 52 9909
TEST_TIME 53 9729
TEST_TIME 54 9594
TEST_TIME 55 9703
TEST_TIME 56 9598
TEST_TIME 57 9515
TEST_TIME 58 9586
TEST_TIME 59 9572
TEST_TIME 60 9563
TEST_TIME 61 9563
TEST_TIME 62 9477
TEST_TIME 63 9474
TEST_TIME 64 9429
TEST_TIME 65 9378
TEST_TIME 66 9420
TEST_TIME 67 9419
TEST_TIME 68 9430
TEST_TIME 69 9366
TEST_TIME 70 9333
TEST_TIME 71 9381
TEST_TIME 72 9395
TEST_TIME 73 9356
TEST_TIME 74 9309
TEST_TIME 75 9474
TEST_TIME 76 9441
TEST_TIME 77 9494
TEST_TIME 78 9509
TEST_TIME 79 9585
TEST_TIME 80 9583
TEST_TIME 81 9473
TEST_TIME 82 9410
TEST_TIME 83 9431
TEST_TIME 84 9389
TEST_TIME 85 9326
TEST_TIME 86 9274
TEST_TIME 87 9223
TEST_TIME 88 9164
TEST_TIME 89 9161
TEST_TIME 90 9135
TEST_TIME 91 9106
TEST_TIME 92 9083
TEST_TIME 93 9123
TEST_TIME 94 9184
TEST_TIME 95 9231
TEST_TIME 96 9259
TEST_TIME 97 9233
TEST_TIME 98 9344
TEST_TIME 99 9351
TEST_TIME 100 9392
TEST_TIME 101 9401
TEST_TIME 102 9399
TEST_TIME 103 9465
TEST_TIME 104 9481
TEST_TIME 105 9438
TEST_TIME 106 9389
TEST_TIME 107 9365
TEST_TIME 108 9348
TEST_TIME 109 9313
TEST_TIME 110 9318
TEST_TIME 111 9310
TEST_TIME 112 9297
TEST_TIME 113 9260
TEST_TIME 114 9241
TEST_TIME 115 9228
TEST_TIME 116 9207
TEST_TIME 117 9160
TEST_TIME 118 9137
TEST_TIME 119 9146
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-03-2023 13:38:54 to 10-03-2023 13:38:58.

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 1509881 1509881 0 0.00 0 35026 12 0 0
doc_list_biud 1509871 1509871 0 0.00 0 88703 39 0 0
sp_add_invoice_to_stock 4964 4856 108 2.17 22 181426 25305 0 108
sp_cancel_adding_invoice 5638 5638 0 0.00 7 84609 1114 0 0
sp_cancel_client_order 1639 1639 0 0.00 6 11549 401 0 0
sp_cancel_customer_reserve 553325 553325 0 0.00 1 52856 92 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 3712 104 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 4 3053 120 0 0
sp_cancel_supplier_invoice 6328 6321 7 0.11 3 88728 870 0 7
sp_cancel_supplier_order 426 426 0 0.00 10 1437925 152441 0 0
sp_cancel_write_off 9547 9547 0 0.00 2 19915 162 0 0
sp_client_order 19785 19785 0 0.00 5 20657 464 0 0
sp_customer_reserve 453603 453603 0 0.00 1 54184 240 0 0
sp_fill_shopping_cart 30348 30348 0 0.00 1 14946 81 0 0
sp_fill_shopping_cart_clo_res 453582 453582 0 0.00 0 16077 17 0 0
sp_get_clo_for_invoice 4818 4818 0 0.00 0 28689 311 0 0
sp_kill_cost_storno 16274 16274 0 0.00 0 12383 76 0 0
sp_kill_qstorno_ret_qs2qd 1122944 1122944 0 0.00 0 88700 40 0 0
sp_kill_qty_storno 591469 591469 0 0.00 0 88702 92 0 0
sp_lock_selected_doc 20063 20063 0 0.00 0 7084 6 0 0
sp_make_cost_storno 26969 26969 0 0.00 0 13905 74 0 0
sp_multiply_rows_for_pdistr 26969 26969 0 0.00 0 11251 36 0 0
sp_multiply_rows_for_qdistr 19785 19785 0 0.00 0 15471 175 0 0
sp_pay_from_customer 6733 6733 0 0.00 6 18390 179 0 0
sp_pay_to_supplier 5663 5663 0 0.00 6 19015 185 0 0
sp_payment_common 12396 12396 0 0.00 2 7597 48 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 1 11535 328 0 0
sp_qd_handle_on_invoice_upd_sts 10455 10455 0 0.00 0 42031 498 0 0
sp_qd_handle_on_reserve_upd_sts 9756 9756 0 0.00 0 12889 57 0 0
sp_reserve_write_off 9756 9756 0 0.00 8 19625 265 0 0
sp_supplier_invoice 5172 5172 0 0.00 9 76165 3139 0 0
sp_supplier_order 5393 5393 0 0.00 8 23813 1346 0 0
srv_aggregate_perf_data 107 107 0 0.00 2522 216550 16819 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 645 131200 37140 0 0
srv_find_qd_qs_mism 1043940 1043940 0 0.00 0 34789 9 0 0
srv_make_invnt_saldo 440 440 0 0.00 33 104300 4207 0 0
srv_make_money_saldo 847 847 0 0.00 1 13746 386 0 0
srv_recalc_idx_stat 6 6 0 0.00 74992 394476 235667 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4857 4857 0 0.00 1 12970 885 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 2 4924 449 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 2662 31 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1980 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 2786 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 511 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1372 41 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 72720 6073 0 0
t$perf-norm:sp_cancel_write_off 907 907 0 0.00 0 3357 23 0 0
t$perf-norm:sp_client_order 19785 19785 0 0.00 0 6114 35 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 2257 15 0 0
t$perf-norm:sp_pay_from_customer 6733 6733 0 0.00 0 4737 18 0 0
t$perf-norm:sp_pay_to_supplier 5663 5663 0 0.00 0 10230 19 0 0
t$perf-norm:sp_reserve_write_off 9756 9756 0 0.00 0 6228 24 0 0
t$perf-norm:sp_supplier_invoice 5172 5172 0 0.00 0 9075 34 0 0
t$perf-norm:sp_supplier_order 5393 5393 0 0.00 0 10785 35 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 333 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 121 5 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 473 9 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 255 4 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 257 86 0 0
v_add_invoice_to_stock 9850 9634 216 2.19 0 8104 30 0 216
v_all_customers 36244 36244 0 0.00 0 183 4 0 0
v_all_suppliers 29788 29788 0 0.00 0 189 4 0 0
v_all_wares 39570 39570 0 0.00 0 263 4 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 4385 21 0 0
v_cancel_client_order 3278 3278 0 0.00 0 886 9 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 3642 15 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 18925 32 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1783 14 0 0
v_cancel_supplier_invoice 792 778 14 1.76 0 643 22 0 14
v_cancel_supplier_order 852 852 0 0.00 0 3406 16 0 0
v_cancel_write_off 1814 1814 0 0.00 0 19184 40 0 0
v_max_id_clo_ord 5393 5393 0 0.00 0 284 5 0 0
v_max_id_clo_res 934 934 0 0.00 0 1638 15 0 0
v_max_id_ord_sup 5170 5170 0 0.00 0 7833 9 0 0
v_max_non_paid_invoice 1332 1332 0 0.00 0 236 8 0 0
v_max_non_paid_realizn 3468 3468 0 0.00 0 4572 9 0 0
v_min_id_clo_ord 5393 5393 0 0.00 0 2003 8 0 0
v_min_id_clo_res 934 934 0 0.00 0 6036 29 0 0
v_min_id_ord_sup 5170 5170 0 0.00 0 4843 8 0 0
v_min_non_paid_invoice 5663 5663 0 0.00 0 6665 15 0 0
v_min_non_paid_realizn 6732 6732 0 0.00 0 6521 22 0 0
v_our_firm 9856 9856 0 0.00 0 260 5 0 0
v_reserve_write_off 19512 19512 0 0.00 0 15822 12 0 0
x_lock_depdocs_on_canc_invoice 11570 11570 0 0.00 0 14144 119 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 18687 395 0 0
x_make_qstorno_1000_1200 5393 5393 0 0.00 2 23797 1183 0 0
x_make_qstorno_1200_2000 5172 5172 0 0.00 4 76148 2927 0 0
x_make_qstorno_2100_3300 453603 453603 0 0.00 0 39880 211 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:31 920 8192000000 8927097664 8982691840 25594832 0.311 5 4 10 0 26632208 169008 868368
11:08:34 11980 8192000000 9706875552 10004979712 792939024 8.825 100 88 174 0 822444560 7905792 21599744
11:09:52 28741 8192000000 9771533984 10080944128 846173600 9.362 100 87 174 0 878112736 11378896 20560240
11:11:27 13211 8192000000 9810944448 10129055744 881139440 9.711 100 90 180 0 919638656 17891936 20607280
11:12:50 11242 8192000000 9874119648 10203463680 915680848 10.053 100 84 168 0 978667696 44889424 18097424
11:14:06 3531 8192000000 9933042688 10275672064 959677536 10.486 100 93 182 1 1050951328 71939136 19334656
11:15:13 3547 8192000000 9959867536 10312347648 989112992 10.773 100 84 165 2 1072073248 66201968 16758288
11:16:21 1349 8192000000 9987759776 10351435776 1014045088 11.014 100 91 176 2 1102653264 71052752 17555424
11:17:25 1175 8192000000 10010961952 10381398016 1030073808 11.169 100 94 177 2 1131563824 83957536 17532480
11:18:28 1090 8192000000 10011121744 10382532608 1052090096 11.381 100 93 181 2 1132362320 61934448 18337776
11:19:30 948 8192000000 10038798704 10414198784 1057541072 11.433 100 93 185 1 1160806160 84303632 18961456
11:20:32 902 8192000000 10038315088 10421739520 1066820224 11.522 100 92 177 1 1158033888 73898672 17314992
11:21:34 920 8192000000 10041099184 10421137408 1070746896 11.559 100 90 177 1 1165244432 75199312 19298224
11:22:35 2888 8192000000 10053750480 10440658944 1078451552 11.633 100 81 152 4 1172837536 79370512 15015472
11:23:39 997 8192000000 10061011824 10447347712 1089297872 11.736 100 90 173 2 1183738800 75464448 18976480
11:24:41 1137 8192000000 10047665952 10431623168 1090601248 11.748 100 96 182 1 1169300816 59013760 19685808
11:25:44 1165 8192000000 10061749904 10449162240 1103732304 11.873 100 96 188 1 1181826336 59109168 18984864
11:26:45 1124 8192000000 10069664480 10459803648 1097056608 11.810 100 96 187 1 1193313728 77165168 19091952
11:27:47 1259 8192000000 10064590736 10456145920 1088959152 11.733 99 95 185 1 1195006880 85807552 20240176
11:28:50 1276 8192000000 10057607360 10446745600 1076115472 11.610 100 85 166 3 1179964224 87565584 16283168
11:29:52 1014 8192000000 10028211392 10414661632 1077997456 11.628 100 92 174 1 1150078064 53490112 18590496
11:30:54 1900 8192000000 10043167456 10440527872 1086682080 11.711 100 69 135 1 1174160832 72718464 14760288
11:31:57 1088 8192000000 10018532624 10400395264 1064776656 11.502 100 88 175 0 1139455952 56014448 18664848
11:32:59 1718 8192000000 10016885872 10398683136 1060805040 11.464 100 80 153 1 1134102624 57156928 16140656
11:34:01 986 8192000000 9985779120 10362658816 1037780848 11.243 100 96 178 2 1105790288 49614976 18394464
11:35:03 1013 8192000000 9988671664 10359177216 1030208944 11.170 100 93 176 1 1108238768 60043872 17985952
11:36:05 1683 8192000000 9957688976 10333220864 1027093712 11.140 100 75 138 1 1077068560 35742144 14232704
11:37:07 2627 8192000000 9981073744 10365464576 1045543248 11.318 100 72 141 2 1104397664 44468384 14386032
11:38:16 1249 8192000000 10009360448 10388484096 1059335952 11.450 99 88 173 1 1135570256 57087312 19146992
11:39:32 11484 8192000000 10022020944 10411999232 1072277504 11.574 100 75 139 1 1140892416 54131088 14483824
11:41:00 4480 8192000000 10052415584 10443304960 1077602848 11.625 100 76 149 2 1171752784 78884480 15265456
11:42:08 1119 8192000000 10095507520 10487549952 1089147280 11.735 100 86 164 2 1215944992 109052480 17745232
11:43:14 2399 8192000000 10109885264 10505879552 1103176832 11.868 100 80 138 2 1222556784 105335952 14044000
11:44:22 2041 8192000000 10131301152 10545016832 1121169904 12.038 100 73 142 3 1244617392 107845040 15602448
11:45:28 7293 8192000000 10154612848 10549776384 1132487184 12.145 100 76 140 1 1266895088 119877360 14530544
11:46:39 1305 8192000000 10190139376 10603814912 1140993920 12.225 100 91 175 1 1310165792 148645728 20526144
11:47:42 1492 8192000000 10243520112 10643152896 1151202368 12.321 100 94 183 1 1357451504 185423424 20825712
11:48:46 4732 8192000000 10250546384 10651197440 1157271056 12.378 100 69 136 1 1355750480 183410880 15068544
11:49:55 930 8192000000 10263195744 10664951808 1171677392 12.513 100 84 162 1 1373429184 182942720 18809072
11:51:00 1081 8192000000 10302788000 10712690688 1177557616 12.567 100 86 164 0 1415235008 218708544 18968848
11:52:04 1982 8192000000 10356456448 10777628672 1185383312 12.640 100 86 163 0 1469526256 264581952 19560992
11:53:09 1293 8192000000 10387290880 10809110528 1191977792 12.702 100 91 177 1 1492917920 280043712 20896416
11:54:14 795 8192000000 10401733424 10818785280 1182237440 12.611 100 97 193 1 1509345200 303877008 23230752
11:55:17 790 8192000000 10366936464 10772328448 1181407424 12.603 100 95 183 0 1472759392 268503024 22848944
11:56:25 2260 8192000000 10389152384 10806317056 1168181008 12.480 100 88 160 0 1489805312 302764976 18859328
11:57:31 918 8192000000 10420973760 10852745216 1165983376 12.459 100 88 171 0 1538683312 352488256 20211680
11:58:36 626 8192000000 10440527040 10850140160 1168158000 12.480 100 96 185 2 1546484288 356390224 21936064
11:59:39 6809 8192000000 10411217376 10862944256 1167065968 12.469 100 54 106 1 1522623792 342134400 13423424
12:00:50 2077 8192000000 10418496432 10844975104 1169772672 12.495 100 71 134 0 1525531424 339124272 16634480
12:01:55 7006 8192000000 10383570480 10836234240 1158860960 12.393 100 50 98 1 1494477280 323306912 12309408
12:03:06 3509 8192000000 10426767792 10851647488 1165903456 12.459 100 60 119 1 1529402352 348116208 15382688
12:04:19 18107 8192000000 10420915872 10886995968 1169708000 12.494 99 47 92 2 1515520048 334494416 11317632
12:05:38 11294 8192000000 10429438640 10892877824 1158884592 12.393 99 36 66 0 1517619216 348904160 9830464
12:07:34 21327 8192000000 10323789920 10792865792 1155006704 12.356 100 30 61 1 1432836736 269919072 7910960
12:08:57 49364 8192000000 10149921280 10659516416 1120240992 12.029 98 15 30 1 1275787184 151835760 3710432
12:10:48 22704 8192000000 10088935872 10510008320 1108263568 11.916 97 65 119 1 1168456160 48248128 11944464
12:12:11 70347 8192000000 10122766464 10603446272 1131621440 12.137 99 19 38 0 1210643280 75294336 3727504
12:14:28 102549 8192000000 10070280400 10526580736 1094885904 11.789 96 20 39 1 1136958176 38397408 3674864
12:17:11 58643 8192000000 10116407984 10572623872 1133682352 12.156 100 36 72 0 1233942240 92672736 7587152
12:19:25 90999 8192000000 10085961248 10564366336 1114709792 11.977 98 19 36 0 1194592544 76016752 3866000
12:22:05 74874 8192000000 10130298800 10600574976 1117820320 12.006 98 14 28 0 1191560592 70516368 3223904
12:24:25 64057 8192000000 10063240608 10488016896 1118614624 12.014 99 35 65 2 1153003824 27493712 6895488
12:26:32 56138 8192000000 10103536144 10553528320 1124912160 12.073 99 16 30 1 1150055760 21953296 3190304
12:28:29 31195 8192000000 10112307552 10554101760 1138472192 12.201 100 43 78 0 1200723216 53552000 8699024
12:30:09 131200 8192000000 10131694720 10635714560 1109683856 11.929 97 26 50 0 1187748832 72459728 5605248
12:33:38 31887 8192000000 10116332336 10550038528 1125741136 12.081 99 48 93 1 1204701648 69860496 9100016
12:35:12 31843 8192000000 10080585072 10545930240 1122734368 12.053 99 17 34 1 1179182128 52708944 3738816
12:36:45 30421 8192000000 10103637728 10529308672 1121421856 12.040 100 45 81 2 1203921456 73752560 8747040
12:38:22 66873 8192000000 10102567888 10592059392 1114687600 11.977 98 17 33 0 1176155568 57562560 3905408
12:40:42 78215 8192000000 10112308176 10576482304 1137340080 12.191 100 13 26 0 1210013056 69976688 2696288
12:43:05 81024 8192000000 10064278096 10511679488 1101577424 11.853 97 16 30 1 1173139296 67999616 3562256
12:45:28 32390 8192000000 10060322672 10504527872 1089471920 11.738 96 22 43 2 1127061232 32912480 4676832
12:47:04 36089 8192000000 10117151248 10564210688 1120424448 12.031 99 29 55 0 1198710192 72007984 6277760
12:48:46 37114 8192000000 10114363376 10584498176 1126320464 12.087 99 29 56 1 1232760256 100011984 6427808
12:50:35 58124 8192000000 10155651056 10598543360 1123164096 12.057 98 26 52 0 1242389504 113465216 5760192
12:52:36 40247 8192000000 10103790432 10532659200 1112199664 11.953 98 38 70 1 1181714640 61980736 7534240
12:54:19 32919 8192000000 10124039376 10594152448 1119414416 12.021 99 31 62 0 1197738480 71672064 6652000
12:56:19 100004 8192000000 10043571280 10547294208 1076539648 11.614 95 11 22 0 1131535920 52827472 2168800
12:59:55 61694 8192000000 10052874704 10540593152 1119053952 12.018 100 22 40 0 1161834960 37853040 4927968
13:01:57 74609 8192000000 10071766208 10527076352 1101892656 11.856 98 8 16 0 1150853984 47328656 1632672
13:04:15 80957 8192000000 10119634048 10590961664 1125948992 12.083 99 18 36 0 1240419632 110387728 4082912
13:06:44 58847 8192000000 10119628352 10565255168 1108245264 11.916 98 17 30 0 1186316320 74995600 3075456
13:08:48 20849 8192000000 10154210480 10583830528 1117186464 12.000 98 31 62 0 1234004752 109519440 7298848
13:10:21 125020 8192000000 10082359792 10590011392 1089503232 11.738 95 23 44 0 1203008192 108300128 5204832
13:13:30 11047 8192000000 10072604688 10499485696 1105142144 11.886 98 51 100 1 1151646064 36816288 9687632
13:14:41 26116 8192000000 10194618016 10634584064 1134624064 12.165 99 48 94 1 1243872320 99705648 9542608
13:16:11 48802 8192000000 10135602576 10614853632 1131057504 12.131 99 26 50 1 1196834304 60962704 4814096
13:18:06 59080 8192000000 10144429264 10592051200 1129876528 12.120 99 22 44 0 1205477040 70888304 4712208
13:20:06 45547 8192000000 10092798016 10529075200 1126411360 12.088 99 31 62 0 1185443120 52952080 6079680
13:21:55 58209 8192000000 10106140432 10529337344 1125961904 12.083 99 34 69 0 1196341744 63668160 6711680
13:24:45 38095 8192000000 10096003760 10544721920 1120339632 12.030 98 41 82 1 1167309248 38918144 8051472
13:26:24 14457 8192000000 10122969744 10553929728 1126445904 12.088 99 63 123 1 1205239184 66601056 12192224
13:27:40 53496 8192000000 10089909520 10562629632 1126247824 12.086 99 29 58 1 1214955792 82993472 5714496
13:29:44 46766 8192000000 10089549792 10529263616 1101547600 11.852 97 22 41 0 1157461072 50798992 5114480
13:31:31 66077 8192000000 10088352496 10545541120 1134491296 12.164 100 9 18 0 1187638784 51120672 2026816
13:34:03 69812 8192000000 10056340816 10519855104 1102413408 11.861 97 21 42 0 1136955728 30144192 4398128
13:36:18 75317 8192000000 9167302160 9462640640 313309776 3.683 28 2 4 0 325571184 11947744 313664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-03-2023 13:39:05 to 10-03-2023 13:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-03-2023 13:39:22 to 10-03-2023 13:39:24.

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          215577
MON$OLDEST_ACTIVE               215578
MON$OLDEST_SNAPSHOT             215578
MON$NEXT_TRANSACTION            215578
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                       2084960
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2907 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2907 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2907 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2907 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'

10.03.23 13:39:25. 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-10 13:39:28 No severe PSQL-related problems occured
10.03.23 13:39:29. End of report.