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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-22 10:37:38
DTS_END                         2022-07-22 10:37:18.7470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47166
TRN_ID                          228701
ATT_ID                          259
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: 9473.14 20447 1136777
customer order: creation 180.38 471 21645
customer order: refuse 14.58 386 1749
order to supplier: creation 47.13 1380 5656
order to supplier: removal 3.48 156944 418
invoice (draft): creation 47.35 3116 5682
invoice (draft): removal 52.72 973 6326
invoice accept: apply 43.77 23832 5252
invoice accept: cancel 47.80 1175 5736
customer reserve: creation 4023.48 228 482817
customer reserve: removal 4704.02 91 564483
realization accept: apply 86.49 286 10379
realization accept: cancel 85.54 169 10265
payment from customer: creation 62.45 187 7494
payment from customer: removal 5.95 139 714
payment to supplier: creation 51.13 217 6136
payment to supplier: removal 3.92 123 470
service: total inventory turnovers 4.10 3811 492
service: total monetary turnovers 7.98 372 957
service: aggregate perf. data 0.84 18969 101
service: refresh index statistics 0.04 196074 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-07-2022 10:38:10 to 22-07-2022 10:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6499
TEST_TIME 2 6665
TEST_TIME 3 6784
TEST_TIME 4 6018
TEST_TIME 5 5967
TEST_TIME 6 6586
TEST_TIME 7 7159
TEST_TIME 8 7087
TEST_TIME 9 7206
TEST_TIME 10 7219
TEST_TIME 11 7193
TEST_TIME 12 7267
TEST_TIME 13 7104
TEST_TIME 14 6954
TEST_TIME 15 7039
TEST_TIME 16 7204
TEST_TIME 17 7256
TEST_TIME 18 7224
TEST_TIME 19 7742
TEST_TIME 20 7941
TEST_TIME 21 8022
TEST_TIME 22 8149
TEST_TIME 23 8161
TEST_TIME 24 8293
TEST_TIME 25 8582
TEST_TIME 26 9060
TEST_TIME 27 9244
TEST_TIME 28 9774
TEST_TIME 29 9770
TEST_TIME 30 10012
TEST_TIME 31 10209
TEST_TIME 32 10142
TEST_TIME 33 10629
TEST_TIME 34 10720
TEST_TIME 35 11133
TEST_TIME 36 10889
TEST_TIME 37 10814
TEST_TIME 38 10835
TEST_TIME 39 10729
TEST_TIME 40 10718
TEST_TIME 41 10816
TEST_TIME 42 10772
TEST_TIME 43 10730
TEST_TIME 44 10583
TEST_TIME 45 10359
TEST_TIME 46 10348
TEST_TIME 47 10336
TEST_TIME 48 10241
TEST_TIME 49 10233
TEST_TIME 50 10257
TEST_TIME 51 10195
TEST_TIME 52 10199
TEST_TIME 53 10243
TEST_TIME 54 10148
TEST_TIME 55 10100
TEST_TIME 56 10048
TEST_TIME 57 9952
TEST_TIME 58 9924
TEST_TIME 59 9863
TEST_TIME 60 9771
TEST_TIME 61 9746
TEST_TIME 62 9761
TEST_TIME 63 9701
TEST_TIME 64 9739
TEST_TIME 65 9719
TEST_TIME 66 9723
TEST_TIME 67 9732
TEST_TIME 68 9683
TEST_TIME 69 9648
TEST_TIME 70 9748
TEST_TIME 71 9834
TEST_TIME 72 9818
TEST_TIME 73 10032
TEST_TIME 74 10050
TEST_TIME 75 10023
TEST_TIME 76 9935
TEST_TIME 77 9877
TEST_TIME 78 9916
TEST_TIME 79 9828
TEST_TIME 80 9789
TEST_TIME 81 9754
TEST_TIME 82 9710
TEST_TIME 83 9669
TEST_TIME 84 9627
TEST_TIME 85 9546
TEST_TIME 86 9548
TEST_TIME 87 9500
TEST_TIME 88 9483
TEST_TIME 89 9478
TEST_TIME 90 9497
TEST_TIME 91 9553
TEST_TIME 92 9501
TEST_TIME 93 9491
TEST_TIME 94 9502
TEST_TIME 95 9567
TEST_TIME 96 9598
TEST_TIME 97 9649
TEST_TIME 98 9672
TEST_TIME 99 9654
TEST_TIME 100 9669
TEST_TIME 101 9610
TEST_TIME 102 9563
TEST_TIME 103 9508
TEST_TIME 104 9524
TEST_TIME 105 9499
TEST_TIME 106 9498
TEST_TIME 107 9464
TEST_TIME 108 9422
TEST_TIME 109 9465
TEST_TIME 110 9417
TEST_TIME 111 9422
TEST_TIME 112 9403
TEST_TIME 113 9390
TEST_TIME 114 9394
TEST_TIME 115 9404
TEST_TIME 116 9409
TEST_TIME 117 9393
TEST_TIME 118 9438
TEST_TIME 119 9506
TEST_TIME 120 9492

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-07-2022 10:38:13 to 22-07-2022 10:38:17.

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 1581557 1581557 0 0.00 0 34655 13 0 0
doc_list_biud 1581557 1581557 0 0.00 0 77226 39 0 0
sp_add_invoice_to_stock 5399 5252 147 2.72 42 205663 23832 0 147
sp_cancel_adding_invoice 5736 5736 0 0.00 6 70313 1176 0 0
sp_cancel_client_order 1749 1749 0 0.00 5 12223 386 0 0
sp_cancel_customer_reserve 564483 564483 0 0.00 1 37114 91 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 9176 140 0 0
sp_cancel_pay_to_supplier 470 470 0 0.00 4 4470 124 0 0
sp_cancel_supplier_invoice 6340 6326 14 0.22 3 77235 973 0 14
sp_cancel_supplier_order 418 418 0 0.00 5 1436730 156944 0 0
sp_cancel_write_off 10265 10265 0 0.00 2 13515 169 0 0
sp_client_order 21645 21645 0 0.00 5 29258 472 0 0
sp_customer_reserve 482817 482817 0 0.00 0 114484 228 0 0
sp_fill_shopping_cart 32974 32974 0 0.00 1 21101 81 0 0
sp_fill_shopping_cart_clo_res 482807 482807 0 0.00 0 14883 17 0 0
sp_get_clo_for_invoice 5218 5218 0 0.00 0 27394 237 0 0
sp_kill_cost_storno 17185 17185 0 0.00 0 14391 72 0 0
sp_kill_qstorno_ret_qs2qd 1145975 1145975 0 0.00 0 77225 40 0 0
sp_kill_qty_storno 604568 604568 0 0.00 0 77226 93 0 0
sp_lock_selected_doc 21598 21598 0 0.00 0 10572 9 0 0
sp_make_cost_storno 29221 29221 0 0.00 0 19361 79 0 0
sp_multiply_rows_for_pdistr 29221 29221 0 0.00 0 10145 40 0 0
sp_multiply_rows_for_qdistr 21644 21644 0 0.00 0 8804 182 0 0
sp_pay_from_customer 7494 7494 0 0.00 5 27654 187 0 0
sp_pay_to_supplier 6136 6136 0 0.00 6 27938 218 0 0
sp_payment_common 13630 13630 0 0.00 2 10114 44 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 1 12211 315 0 0
sp_qd_handle_on_invoice_upd_sts 10948 10948 0 0.00 0 16148 487 0 0
sp_qd_handle_on_reserve_upd_sts 10379 10379 0 0.00 0 14262 71 0 0
sp_reserve_write_off 10379 10379 0 0.00 8 14709 286 0 0
sp_supplier_invoice 5682 5682 0 0.00 9 83221 3117 0 0
sp_supplier_order 5656 5656 0 0.00 7 28800 1381 0 0
srv_aggregate_perf_data 101 101 0 0.00 2396 199119 18970 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 751 112803 35020 0 0
srv_find_qd_qs_mism 1085663 1085663 0 0.00 0 34652 9 0 0
srv_make_invnt_saldo 492 492 0 0.00 46 68936 3812 0 0
srv_make_money_saldo 957 957 0 0.00 1 15580 372 0 0
srv_recalc_idx_stat 5 5 0 0.00 51833 309824 196074 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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 5252 5252 0 0.00 1 15898 891 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 5484 490 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 3666 26 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 2170 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 1022 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 470 470 0 0.00 0 1966 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 845 31 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 70336 6456 0 0
t$perf-norm:sp_cancel_write_off 1098 1098 0 0.00 0 4994 23 0 0
t$perf-norm:sp_client_order 21645 21645 0 0.00 0 8503 39 0 0
t$perf-norm:sp_customer_reserve 1073 1073 0 0.00 0 4035 18 0 0
t$perf-norm:sp_pay_from_customer 7494 7494 0 0.00 0 7093 20 0 0
t$perf-norm:sp_pay_to_supplier 6136 6136 0 0.00 0 5649 21 0 0
t$perf-norm:sp_reserve_write_off 10379 10379 0 0.00 0 7283 24 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 6710 34 0 0
t$perf-norm:sp_supplier_order 5656 5656 0 0.00 0 2821 32 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 289 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 133 5 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 1537 14 0 0
t$perf-norm:srv_make_money_saldo 957 957 0 0.00 0 4350 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 15 198 99 0 0
v_add_invoice_to_stock 10718 10424 294 2.74 0 4669 25 0 294
v_all_customers 40066 40066 0 0.00 0 67 4 0 0
v_all_suppliers 31708 31708 0 0.00 0 86 4 0 0
v_all_wares 43286 43286 0 0.00 0 155 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 492 15 0 0
v_cancel_client_order 3498 3498 0 0.00 0 4070 12 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 2110 13 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1437 12 0 0
v_cancel_payment_to_supplier 940 940 0 0.00 0 1263 11 0 0
v_cancel_supplier_invoice 850 822 28 3.29 0 1254 25 0 28
v_cancel_supplier_order 836 836 0 0.00 0 2329 14 0 0
v_cancel_write_off 2196 2196 0 0.00 0 6744 27 0 0
v_max_id_clo_ord 5656 5656 0 0.00 0 328 5 0 0
v_max_id_clo_res 1073 1073 0 0.00 0 2144 15 0 0
v_max_id_ord_sup 5673 5673 0 0.00 0 5434 10 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 59 7 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 383 7 0 0
v_min_id_clo_ord 5656 5656 0 0.00 0 4771 10 0 0
v_min_id_clo_res 1073 1073 0 0.00 0 1918 13 0 0
v_min_id_ord_sup 5673 5673 0 0.00 0 5232 11 0 0
v_min_non_paid_invoice 6136 6136 0 0.00 0 10107 14 0 0
v_min_non_paid_realizn 7494 7494 0 0.00 0 5288 17 0 0
v_our_firm 10806 10806 0 0.00 0 117 5 0 0
v_reserve_write_off 20758 20758 0 0.00 0 4251 11 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 16515 110 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 3289 295 0 0
x_make_qstorno_1000_1200 5656 5656 0 0.00 2 28783 1212 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 4 83186 2898 0 0
x_make_qstorno_2100_3300 482816 482816 0 0.00 0 114467 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-07-2022 10:38:18 to 22-07-2022 10:38:22.

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:22 4 8192000000 8803208256 8816222208 7978416 0.097 4 1 2 0 8083600 69408 35776
08:08:22 25601 8192000000 9712635744 10006970368 785693296 8.751 100 89 178 0 814660896 7585584 21382016
08:09:56 11893 8192000000 9762428896 10069684224 839008672 9.290 100 92 182 0 872413920 12104272 21300976
08:11:10 14903 8192000000 9797556752 10112716800 863492592 9.535 100 93 186 0 904089088 19337584 21258912
08:12:31 4873 8192000000 9854426320 10183516160 907730560 9.975 100 79 157 0 959197040 34388016 17078464
08:13:39 5184 8192000000 9917168496 10257006592 938948080 10.283 100 86 172 0 1032063920 74667808 18448032
08:14:47 3551 8192000000 9951105552 10302996480 978944960 10.674 100 90 172 1 1067946928 71233808 17768160
08:15:53 1894 8192000000 10000841680 10358476800 1007695200 10.953 100 93 182 1 1115536304 89216000 18625104
08:16:57 1148 8192000000 10007252368 10371977216 1035136560 11.218 100 96 186 0 1128531856 74284992 19110304
08:18:00 1157 8192000000 10015218656 10386845696 1051178688 11.372 100 93 179 1 1138219504 70093296 16947520
08:19:05 3573 8192000000 10018334880 10391949312 1058580976 11.443 100 82 160 0 1138879504 64856992 15441536
08:20:09 990 8192000000 10041103808 10420363264 1071079280 11.562 100 96 183 3 1159922224 70339616 18503328
08:21:12 1154 8192000000 10083944976 10468282368 1078109856 11.629 100 99 194 0 1203970816 107296288 18564672
08:22:15 1075 8192000000 10060647008 10443444224 1083720048 11.683 100 95 184 0 1183998880 82214336 18064496
08:23:17 980 8192000000 10048358000 10431803392 1083732128 11.683 100 95 186 1 1172749008 69263296 19753584
08:24:19 1048 8192000000 10044676224 10430300160 1086556848 11.710 100 92 174 2 1166349312 60272256 19520208
08:25:22 2999 8192000000 10048755440 10442825728 1089949024 11.742 100 74 146 3 1168212208 63485472 14777712
08:26:26 1358 8192000000 10042347200 10430742528 1091532352 11.757 100 85 161 3 1164054160 56744816 15776992
08:27:28 1166 8192000000 10062459264 10453762048 1096752736 11.807 100 94 181 2 1182762272 66979552 19029984
08:28:30 1162 8192000000 10059251424 10452365312 1100921152 11.846 100 99 192 1 1180617520 59586896 20109472
08:29:32 1126 8192000000 10047513296 10433355776 1082260864 11.669 100 95 184 1 1171163216 68545008 20357344
08:30:34 1434 8192000000 10052647168 10439929856 1081094576 11.658 100 95 182 2 1179998928 79051120 19853232
08:31:37 1374 8192000000 9995136624 10371649536 1038788848 11.253 99 78 150 3 1116086512 62036304 15261360
08:32:39 7400 8192000000 9992115632 10368598016 1034767552 11.214 100 56 106 1 1114762288 69320736 10674000
08:33:47 1001 8192000000 10009966720 10385719296 1053943552 11.398 100 88 165 2 1130078240 58907040 17227648
08:34:49 1187 8192000000 9981165120 10357788672 1044789344 11.311 100 84 163 2 1102111344 40773984 16548016
08:35:51 3718 8192000000 10007445760 10384007168 1046308144 11.325 99 79 143 4 1121993488 60896608 14788736
08:36:56 2577 8192000000 9999026832 10378313728 1043964496 11.303 100 78 146 1 1130048704 70341632 15742576
08:38:04 32240 8192000000 10017267248 10402635776 1061428048 11.470 100 72 137 0 1129214032 53185488 14600496
08:39:43 1110 8192000000 10025550256 10411474944 1085854144 11.703 100 81 152 1 1146627776 45640992 15132640
08:40:51 16600 8192000000 10065671632 10461106176 1102112096 11.858 100 72 141 0 1187923616 70872160 14939360
08:42:13 4437 8192000000 10105314512 10493022208 1114393248 11.974 100 86 158 2 1223850160 92539504 16917408
08:43:19 3835 8192000000 10138503440 10551349248 1121570624 12.042 100 69 124 3 1247139936 112492880 13076432
08:44:27 889 8192000000 10134649232 10537304064 1141919776 12.234 100 89 164 2 1253314944 93368368 18026800
08:45:32 1368 8192000000 10167117104 10561478656 1149224672 12.302 100 96 175 0 1284161952 115679472 19257808
08:46:38 1638 8192000000 10199807424 10596802560 1157042816 12.376 100 74 137 0 1312382240 140754128 14585296
08:47:44 840 8192000000 10222055792 10617298944 1153822544 12.345 99 94 176 2 1330446864 157039088 19585232
08:48:48 807 8192000000 10243581824 10654597120 1174608704 12.540 100 98 189 1 1357086432 160980304 21497424
08:49:54 1741 8192000000 10294718160 10705207296 1177607776 12.568 100 69 133 0 1404062064 211593680 14860608
08:51:00 2479 8192000000 10301583856 10707230720 1179651440 12.587 100 76 144 1 1413477072 218381760 15443872
08:52:11 732 8192000000 10371466832 10793152512 1189742384 12.681 100 96 181 0 1481991088 269853904 22394800
08:53:14 905 8192000000 10391564128 10814750720 1199949024 12.776 100 97 181 3 1502710352 281975456 20785872
08:54:23 820 8192000000 10416836688 10827182080 1185697728 12.643 100 86 160 3 1525463536 321369328 18396480
08:55:28 971 8192000000 10412500016 10860056576 1195462576 12.734 100 91 180 0 1519339824 303269408 20607840
08:56:34 1832 8192000000 10409425920 10831126528 1196055712 12.740 100 88 175 0 1515903808 299737856 20110240
08:57:37 879 8192000000 10428257248 10856415232 1182237136 12.611 99 97 183 1 1534686176 330402960 22046080
08:58:41 834 8192000000 10454275728 10861514752 1193059344 12.712 100 95 188 0 1558298816 342938960 22300512
08:59:44 5549 8192000000 10469283488 10907951104 1193270112 12.714 100 65 130 0 1578599584 368806944 16522528
09:00:55 1229 8192000000 10461233360 10908667904 1174486816 12.539 100 73 139 0 1563322128 370592752 18242560
09:02:01 3805 8192000000 10467462928 10944942080 1181878720 12.608 100 66 128 0 1603206160 404979424 16348016
09:03:07 4169 8192000000 10448429936 10928996352 1171784352 12.514 100 62 123 1 1551622832 364465184 15373296
09:04:17 10493 8192000000 10410907456 10870812672 1163291952 12.434 100 36 72 0 1522449680 349543888 9613840
09:05:39 17280 8192000000 10333806576 10784071680 1155173664 12.358 99 38 74 1 1438327616 274920112 8233840
09:06:57 25231 8192000000 10359937376 10808135680 1152828096 12.336 100 27 54 0 1459638448 299838736 6971616
09:08:25 21666 8192000000 10230240320 10679836672 1117437312 12.003 99 45 83 1 1323668896 197130288 9101296
09:10:03 12660 8192000000 10214546112 10699296768 1121178576 12.038 99 16 32 0 1325411200 199918624 4314000
09:11:25 37205 8192000000 10159380400 10634403840 1136858432 12.186 100 13 26 0 1280908096 141177216 2872448
09:13:03 34757 8192000000 10065478848 10496294912 1137259760 12.190 100 31 57 0 1160268064 16922448 6085856
09:14:40 82977 8192000000 10112140608 10605363200 1097377200 11.813 96 15 30 0 1194539200 93956800 3205200
09:17:17 64386 8192000000 10082494304 10565578752 1097704496 11.816 97 15 28 0 1158591456 57761248 3125712
09:19:22 71029 8192000000 10138333504 10603122688 1092099072 11.763 96 11 22 0 1166625808 72239344 2287392
09:21:50 87662 8192000000 10084992176 10507378688 1069860880 11.551 95 41 76 0 1161279056 83316608 8101568
09:24:22 82157 8192000000 10060758400 10484039680 1087478896 11.719 97 56 94 2 1155607968 59098208 9030864
09:26:45 34024 8192000000 10107765584 10546962432 1117068976 11.999 100 29 56 2 1207644592 84405952 6169664
09:28:39 40102 8192000000 10092111536 10582958080 1127508256 12.098 100 27 54 0 1200566704 67123952 5934496
09:30:26 44439 8192000000 10050337760 10493485056 1110079952 11.933 98 15 30 0 1136917920 23726464 3111504
09:32:13 45423 8192000000 10064195056 10515517440 1122163424 12.047 99 8 17 0 1154693712 30700080 1830208
09:34:02 112803 8192000000 10065408048 10564788224 1109264944 11.925 98 15 28 0 1149327744 37545488 2517312
09:37:21 20657 8192000000 10078155792 10522558464 1133307696 12.153 99 33 64 2 1173163408 32849392 7006320
09:38:43 65829 8192000000 10060978016 10576109568 1114976800 11.980 98 12 22 0 1200538816 83352272 2209744
09:40:49 29657 8192000000 10117855968 10587799552 1124107344 12.066 98 37 74 1 1193616112 61957472 7551296
09:42:24 24593 8192000000 10154010880 10591338496 1125313520 12.077 98 32 62 0 1231830720 99732992 6784208
09:43:49 23754 8192000000 10157658976 10626134016 1117571808 12.004 97 29 56 0 1248414624 124515680 6327136
09:45:38 19857 8192000000 10236516768 10672291840 1142814048 12.242 99 40 80 0 1318807056 167231568 8761440
09:47:01 31468 8192000000 10237920848 10687873024 1116919856 11.998 97 41 81 0 1312731680 186198192 9613632
09:48:33 32045 8192000000 10197914096 10629074944 1114003088 11.970 98 35 70 2 1271804064 149965264 7835712
09:50:08 112780 8192000000 10096999744 10570850304 1095670800 11.797 97 19 36 0 1164833200 65753520 3408880
09:53:03 74141 8192000000 10102634960 10538565632 1115897136 11.988 99 46 88 1 1207630272 82114704 9618432
09:55:26 68592 8192000000 10043067568 10526584832 1097132400 11.810 98 16 31 1 1137860048 37451872 3275776
09:57:38 25384 8192000000 10059317344 10505265152 1122453152 12.050 100 35 67 1 1168500240 39383552 6663536
09:59:06 85740 8192000000 10079917024 10554171392 1134819584 12.167 100 16 32 0 1188450720 50143472 3487664
10:01:42 87217 8192000000 10079449712 10532126720 1142121152 12.235 100 15 30 0 1182079520 36901152 3057216
10:04:09 51361 8192000000 10073101968 10530750464 1097529248 11.814 96 23 47 0 1137483520 34845600 5108672
10:06:04 53396 8192000000 10061131360 10504994816 1076760240 11.617 95 20 40 1 1128291952 47413888 4117824
10:08:01 52498 8192000000 10061045792 10554212352 1105350768 11.888 98 34 68 1 1193617856 81445040 6822048
10:09:56 24169 8192000000 10079198704 10511286272 1104705792 11.882 98 35 70 0 1153722128 42174944 6841392
10:11:24 34219 8192000000 10101618848 10594578432 1121387120 12.040 100 31 62 1 1208726192 80512080 6826992
10:13:12 82316 8192000000 10107267744 10567737344 1117135728 12.000 99 26 52 0 1185079472 63274704 4669040
10:15:39 68751 8192000000 10123169984 10573185024 1117722720 12.005 98 39 76 0 1175991456 50694976 7573760
10:17:53 28585 8192000000 10109502240 10559983616 1143314896 12.247 100 25 50 1 1208451040 59924288 5211856
10:19:22 73478 8192000000 10101848608 10566639616 1102571648 11.862 97 31 58 0 1181087680 71990192 6525840
10:22:36 53295 8192000000 10079714416 10550046720 1117625248 12.005 99 13 26 0 1193591120 72482720 3483152
10:24:30 51908 8192000000 10081807904 10548277248 1130442496 12.126 99 14 28 0 1192296144 58209968 3643680
10:27:01 72283 8192000000 10092117376 10559569920 1113948768 11.970 97 30 60 1 1174195248 53463568 6782912
10:30:06 42091 8192000000 10145879728 10603425792 1126354752 12.087 99 27 54 0 1230747376 98711296 5681328
10:31:58 76276 8192000000 10129594704 10576244736 1105768208 11.892 98 27 54 0 1246211264 134168304 6274752
10:34:55 15005 8192000000 10145125728 10578026496 1109021600 11.923 99 46 89 0 1231015856 112353344 9640912
10:36:13 55769 8192000000 10089730512 10575532032 1107430160 11.908 99 17 34 0 1218946096 107837248 3678688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-07-2022 10:38:24 to 22-07-2022 10:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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 22-07-2022 10:38:41 to 22-07-2022 10:38:43.

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          234211
MON$OLDEST_ACTIVE               234212
MON$OLDEST_SNAPSHOT             234212
MON$NEXT_TRANSACTION            234212
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                       2076448
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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