Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.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 15033976 kB
MemAvailable 30417616 kB
Buffers 0 kB
Cached 16645848 kB
SwapCached 3176 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-02-18 13:37:28
DTS_END                         2023-02-18 13:37:28.3470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57880
TRN_ID                          213188
ATT_ID                          647
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: 8767.69 25996 1052123
customer order: creation 166.42 451 19970
customer order: refuse 14.19 448 1703
order to supplier: creation 43.87 1423 5264
order to supplier: removal 3.09 182548 371
invoice (draft): creation 44.48 3480 5337
invoice (draft): removal 47.21 1023 5665
invoice accept: apply 41.03 26210 4924
invoice accept: cancel 42.74 1217 5129
customer reserve: creation 3838.83 244 460660
customer reserve: removal 4241.13 104 508936
realization accept: apply 81.25 305 9750
realization accept: cancel 79.60 177 9552
payment from customer: creation 57.06 195 6847
payment from customer: removal 5.59 138 671
payment to supplier: creation 46.23 200 5548
payment to supplier: removal 3.16 180 379
service: total inventory turnovers 3.62 5026 434
service: total monetary turnovers 7.30 390 876
service: aggregate perf. data 0.85 20457 102
service: refresh index statistics 0.04 275709 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-02-2023 13:38:37 to 18-02-2023 13:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6465
TEST_TIME 2 4575
TEST_TIME 3 5125
TEST_TIME 4 5373
TEST_TIME 5 5334
TEST_TIME 6 6153
TEST_TIME 7 5868
TEST_TIME 8 5856
TEST_TIME 9 5882
TEST_TIME 10 5936
TEST_TIME 11 6132
TEST_TIME 12 6448
TEST_TIME 13 6492
TEST_TIME 14 6494
TEST_TIME 15 6634
TEST_TIME 16 6702
TEST_TIME 17 6893
TEST_TIME 18 6962
TEST_TIME 19 7096
TEST_TIME 20 7108
TEST_TIME 21 7170
TEST_TIME 22 7452
TEST_TIME 23 7603
TEST_TIME 24 7817
TEST_TIME 25 7800
TEST_TIME 26 7796
TEST_TIME 27 8056
TEST_TIME 28 8665
TEST_TIME 29 8848
TEST_TIME 30 9173
TEST_TIME 31 9673
TEST_TIME 32 9820
TEST_TIME 33 10133
TEST_TIME 34 10431
TEST_TIME 35 10348
TEST_TIME 36 10195
TEST_TIME 37 10084
TEST_TIME 38 10287
TEST_TIME 39 10095
TEST_TIME 40 10128
TEST_TIME 41 9992
TEST_TIME 42 9900
TEST_TIME 43 9757
TEST_TIME 44 9686
TEST_TIME 45 9755
TEST_TIME 46 9707
TEST_TIME 47 9565
TEST_TIME 48 9518
TEST_TIME 49 9653
TEST_TIME 50 9671
TEST_TIME 51 9595
TEST_TIME 52 9544
TEST_TIME 53 9747
TEST_TIME 54 9658
TEST_TIME 55 9491
TEST_TIME 56 9417
TEST_TIME 57 9361
TEST_TIME 58 9270
TEST_TIME 59 9223
TEST_TIME 60 9166
TEST_TIME 61 9162
TEST_TIME 62 9092
TEST_TIME 63 9086
TEST_TIME 64 9022
TEST_TIME 65 8940
TEST_TIME 66 8983
TEST_TIME 67 8931
TEST_TIME 68 8896
TEST_TIME 69 9052
TEST_TIME 70 9041
TEST_TIME 71 9149
TEST_TIME 72 9263
TEST_TIME 73 9195
TEST_TIME 74 9208
TEST_TIME 75 9310
TEST_TIME 76 9306
TEST_TIME 77 9219
TEST_TIME 78 9225
TEST_TIME 79 9245
TEST_TIME 80 9232
TEST_TIME 81 9311
TEST_TIME 82 9203
TEST_TIME 83 9233
TEST_TIME 84 9207
TEST_TIME 85 9181
TEST_TIME 86 9168
TEST_TIME 87 9131
TEST_TIME 88 9083
TEST_TIME 89 9061
TEST_TIME 90 9011
TEST_TIME 91 8960
TEST_TIME 92 8945
TEST_TIME 93 8908
TEST_TIME 94 8827
TEST_TIME 95 8869
TEST_TIME 96 8890
TEST_TIME 97 8875
TEST_TIME 98 8878
TEST_TIME 99 8906
TEST_TIME 100 8917
TEST_TIME 101 8941
TEST_TIME 102 8988
TEST_TIME 103 8978
TEST_TIME 104 8993
TEST_TIME 105 8986
TEST_TIME 106 8982
TEST_TIME 107 9084
TEST_TIME 108 9084
TEST_TIME 109 9039
TEST_TIME 110 8988
TEST_TIME 111 8957
TEST_TIME 112 8946
TEST_TIME 113 8918
TEST_TIME 114 8898
TEST_TIME 115 8877
TEST_TIME 116 8831
TEST_TIME 117 8798
TEST_TIME 118 8813
TEST_TIME 119 8846
TEST_TIME 120 8797

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-02-2023 13:38:41 to 18-02-2023 13:38:44.

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 1475757 1475757 0 0.00 0 35581 13 0 0
doc_list_biud 1475755 1475755 0 0.00 0 40665 42 0 0
sp_add_invoice_to_stock 5079 4924 155 3.05 51 252290 26211 0 155
sp_cancel_adding_invoice 5129 5129 0 0.00 7 112399 1217 0 0
sp_cancel_client_order 1703 1703 0 0.00 6 13662 448 0 0
sp_cancel_customer_reserve 508936 508936 0 0.00 1 25338 105 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 4 5518 139 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 4 12375 180 0 0
sp_cancel_supplier_invoice 5679 5665 14 0.24 4 40674 1024 0 14
sp_cancel_supplier_order 371 371 0 0.00 22 1548471 182548 0 0
sp_cancel_write_off 9552 9552 0 0.00 2 26691 177 0 0
sp_client_order 19970 19970 0 0.00 6 11399 452 0 0
sp_customer_reserve 460660 460660 0 0.00 0 99352 245 0 0
sp_fill_shopping_cart 30568 30568 0 0.00 1 26586 94 0 0
sp_fill_shopping_cart_clo_res 460628 460628 0 0.00 0 22602 19 0 0
sp_get_clo_for_invoice 4886 4886 0 0.00 0 18466 304 0 0
sp_kill_cost_storno 15731 15731 0 0.00 0 35571 85 0 0
sp_kill_qstorno_ret_qs2qd 1033460 1033460 0 0.00 0 40662 46 0 0
sp_kill_qty_storno 545991 545991 0 0.00 0 40663 105 0 0
sp_lock_selected_doc 20067 20067 0 0.00 0 10942 10 0 0
sp_make_cost_storno 27031 27031 0 0.00 0 25429 94 0 0
sp_multiply_rows_for_pdistr 27030 27030 0 0.00 0 8695 46 0 0
sp_multiply_rows_for_qdistr 19970 19970 0 0.00 0 11079 158 0 0
sp_pay_from_customer 6847 6847 0 0.00 5 12251 196 0 0
sp_pay_to_supplier 5548 5548 0 0.00 6 13049 200 0 0
sp_payment_common 12395 12395 0 0.00 2 12226 50 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 1 13648 371 0 0
sp_qd_handle_on_invoice_upd_sts 10015 10015 0 0.00 0 26010 553 0 0
sp_qd_handle_on_reserve_upd_sts 9749 9749 0 0.00 0 17850 63 0 0
sp_reserve_write_off 9750 9750 0 0.00 9 24116 305 0 0
sp_supplier_invoice 5337 5337 0 0.00 10 86197 3480 0 0
sp_supplier_order 5264 5264 0 0.00 8 46664 1424 0 0
srv_aggregate_perf_data 102 102 0 0.00 2864 241515 20457 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 663 143492 36084 0 0
srv_find_qd_qs_mism 1004155 1004155 0 0.00 0 28100 9 0 0
srv_make_invnt_saldo 434 434 0 0.00 42 198821 5026 0 0
srv_make_money_saldo 876 876 0 0.00 1 18690 391 0 0
srv_recalc_idx_stat 5 5 0 0.00 67207 515651 275709 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 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 4928 4928 0 0.00 1 25130 980 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 9297 493 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 11164 35 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 6997 48 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 603 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 1968 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 1977 49 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 107098 6760 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 11112 36 0 0
t$perf-norm:sp_client_order 19970 19970 0 0.00 0 11065 40 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 5772 22 0 0
t$perf-norm:sp_pay_from_customer 6847 6847 0 0.00 0 9080 21 0 0
t$perf-norm:sp_pay_to_supplier 5548 5548 0 0.00 0 10219 24 0 0
t$perf-norm:sp_reserve_write_off 9750 9750 0 0.00 0 10770 27 0 0
t$perf-norm:sp_supplier_invoice 5337 5337 0 0.00 0 9571 44 0 0
t$perf-norm:sp_supplier_order 5264 5264 0 0.00 0 8164 37 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 387 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1118 33 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 2363 22 0 0
t$perf-norm:srv_make_money_saldo 876 876 0 0.00 0 4617 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 248 110 0 0
v_add_invoice_to_stock 10082 9772 310 3.07 0 5780 26 0 310
v_all_customers 36650 36650 0 0.00 0 82 4 0 0
v_all_suppliers 29340 29340 0 0.00 0 75 4 0 0
v_all_wares 39938 39938 0 0.00 0 87 4 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 1132 15 0 0
v_cancel_client_order 3406 3406 0 0.00 0 4249 11 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2592 15 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 1 1762 16 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 3435 19 0 0
v_cancel_supplier_invoice 762 734 28 3.67 0 1737 29 0 28
v_cancel_supplier_order 742 742 0 0.00 0 4421 22 0 0
v_cancel_write_off 1826 1826 0 0.00 0 888 22 0 0
v_max_id_clo_ord 5264 5264 0 0.00 0 2117 6 0 0
v_max_id_clo_res 931 931 0 0.00 0 2795 16 0 0
v_max_id_ord_sup 5335 5335 0 0.00 0 6718 12 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 68 7 0 0
v_max_non_paid_realizn 3523 3523 0 0.00 0 6490 11 0 0
v_min_id_clo_ord 5264 5264 0 0.00 0 2090 8 0 0
v_min_id_clo_res 931 931 0 0.00 0 8067 17 0 0
v_min_id_ord_sup 5335 5335 0 0.00 0 6171 10 0 0
v_min_non_paid_invoice 5548 5548 0 0.00 0 9307 22 0 0
v_min_non_paid_realizn 6847 6847 0 0.00 0 6921 18 0 0
v_our_firm 9936 9936 0 0.00 0 65 5 0 0
v_reserve_write_off 19498 19498 0 0.00 0 3237 10 0 0
x_lock_depdocs_on_canc_invoice 10427 10427 0 0.00 0 14223 133 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 6303 406 0 0
x_make_qstorno_1000_1200 5264 5264 0 0.00 2 46652 1254 0 0
x_make_qstorno_1200_2000 5337 5337 0 0.00 4 86184 3197 0 0
x_make_qstorno_2100_3300 460660 460660 0 0.00 0 99350 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-02-2023 13:38:45 to 18-02-2023 13:38:49.

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:32 926 8192000000 8918002880 8972808192 7411712 0.090 3 1 2 0 7556336 108848 35776
11:08:34 23642 8192000000 9724392288 10022047744 798992368 8.886 100 93 187 0 829355392 7688848 22674176
11:10:01 16055 8192000000 9762383392 10070294528 837189632 9.272 100 92 184 0 871155616 12358128 21607856
11:11:23 16319 8192000000 9799129680 10117120000 869514944 9.595 100 85 170 0 908192480 18474752 20202784
11:12:43 12518 8192000000 9848740304 10175504384 903567424 9.934 100 84 168 0 961506496 39603792 18335280
11:13:59 8621 8192000000 9893790512 10236014592 939332592 10.286 100 74 146 0 1007766624 53338112 15095920
11:15:10 3962 8192000000 9939712944 10286268416 977635328 10.661 100 88 173 0 1048470320 52908192 17926800
11:16:16 1394 8192000000 9966012400 10325135360 1010758928 10.983 100 85 164 0 1086722640 59668640 16295072
11:17:20 1408 8192000000 10004380144 10370846720 1028528576 11.154 100 92 179 1 1126029392 80161568 17339248
11:18:24 1499 8192000000 10025447760 10398560256 1047396272 11.336 100 90 178 1 1149693472 83808624 18488576
11:19:27 1447 8192000000 10035165808 10416566272 1061085104 11.467 100 85 166 0 1162703472 84391104 17227264
11:20:30 1130 8192000000 10048126672 10425552896 1067607088 11.529 100 93 176 0 1164978544 80511712 16859744
11:21:36 1181 8192000000 10048799024 10431299584 1072361872 11.575 100 88 169 0 1174514624 85503408 16649344
11:22:39 897 8192000000 10037647904 10421104640 1076167824 11.611 100 93 173 1 1160009776 67184608 16657344
11:23:42 1108 8192000000 10080268400 10468155392 1081387568 11.661 100 96 177 0 1200908352 101440448 18080336
11:24:44 3317 8192000000 10052591904 10442100736 1083038176 11.676 100 78 143 3 1171195920 74593376 13564368
11:25:50 6512 8192000000 10043461120 10438553600 1085655792 11.701 100 66 127 2 1171835088 73676736 12502560
11:27:03 868 8192000000 10051698064 10440499200 1094571056 11.786 100 89 174 1 1172276992 60124976 17580960
11:28:04 2125 8192000000 10056529536 10450337792 1089314016 11.736 100 79 154 0 1180809936 77709088 13786832
11:29:08 1042 8192000000 10048018800 10432688128 1085229456 11.697 100 95 181 0 1169335120 65343600 18762064
11:30:10 1889 8192000000 10055975392 10450038784 1094492960 11.785 100 89 172 1 1184287568 71622896 18171712
11:31:14 1107 8192000000 10058505904 10448867328 1090011520 11.743 100 86 158 0 1182988704 76149504 16827680
11:32:16 1133 8192000000 10066794144 10455719936 1088555040 11.729 100 93 183 0 1197873008 90754352 18563616
11:33:18 1955 8192000000 10007059232 10394755072 1064370640 11.498 99 73 135 4 1131799712 53247696 14181376
11:34:21 1551 8192000000 9986788480 10359549952 1025207216 11.122 98 78 150 3 1107582464 67837184 14538064
11:35:24 1202 8192000000 10016258320 10400038912 1058379024 11.441 100 89 170 2 1139129152 62830992 17919136
11:36:29 1117 8192000000 9992776480 10365878272 1040993744 11.274 99 88 171 2 1114248320 54914032 18340544
11:37:31 2841 8192000000 10002560064 10387443712 1055182672 11.410 100 70 125 2 1131005664 63419248 12403744
11:38:46 35257 8192000000 10023842176 10408042496 1068631152 11.539 100 63 126 0 1127327168 46198656 12497360
11:40:30 691 8192000000 10050144720 10430304256 1069925632 11.551 99 85 152 3 1167043968 83741264 13377072
11:41:35 2871 8192000000 10086292576 10476044288 1097198160 11.811 100 76 136 1 1199437920 88845616 13394144
11:42:41 3541 8192000000 10110577840 10501963776 1106725616 11.901 100 80 150 3 1232626704 109471568 16429520
11:43:48 3642 8192000000 10134911360 10528280576 1121357136 12.040 100 84 159 0 1256761072 119067200 16336736
11:44:56 2004 8192000000 10174548576 10566275072 1123257568 12.058 100 79 150 0 1284683232 144811824 16613840
11:46:07 5775 8192000000 10177097696 10588200960 1127978416 12.102 100 68 126 0 1296274992 155142048 13154528
11:47:16 15036 8192000000 10198965888 10610880512 1134352304 12.162 100 62 122 2 1332256416 184872240 13031872
11:48:40 1100 8192000000 10254151184 10660663296 1155621840 12.362 100 96 173 1 1367107648 193230592 18255216
11:49:44 1351 8192000000 10259606416 10664280064 1164325136 12.444 100 94 185 2 1373597008 189254768 20017104
11:50:49 1368 8192000000 10307803296 10705039360 1173662320 12.531 100 96 188 3 1418584720 224622064 20300336
11:51:55 1293 8192000000 10341545584 10749997056 1178221072 12.574 100 95 189 2 1449275104 249623280 21430752
11:53:00 834 8192000000 10385005232 10787655680 1179443648 12.585 100 95 186 1 1492351520 291031232 21876640
11:54:05 1240 8192000000 10399962208 10820362240 1186772704 12.653 100 96 183 4 1508527696 302030304 19724688
11:55:09 1838 8192000000 10437480128 10856374272 1181697584 12.606 100 91 178 2 1542612432 339774256 21140592
11:56:13 656 8192000000 10453731088 10863243264 1182834208 12.617 100 94 184 0 1562352544 357637568 21880768
11:57:18 843 8192000000 10515519344 10927497216 1191703152 12.699 100 98 189 2 1624962368 410636112 22623104
11:58:23 1212 8192000000 10504664064 10954321920 1201532992 12.791 100 95 182 1 1612912992 389391920 21988080
11:59:27 1393 8192000000 10520734064 10977148928 1206111968 12.833 100 96 192 1 1627576992 398502384 22962640
12:00:32 859 8192000000 10514137552 10947424256 1195940560 12.739 100 84 150 3 1618760752 404363616 18456576
12:01:42 5101 8192000000 10516562320 10967011328 1187767840 12.663 100 55 105 0 1626361328 424718432 13875056
12:02:56 815 8192000000 10573193456 11006550016 1192859712 12.710 100 96 191 0 1676690848 459627904 24203232
12:04:02 2396 8192000000 10540891536 10974666752 1185981104 12.646 100 83 161 0 1653974368 447738208 20255056
12:05:06 10049 8192000000 10474428704 10993393664 1178527600 12.576 100 48 95 2 1597882656 406574208 12780848
12:06:18 20098 8192000000 10444979696 10933526528 1157869760 12.383 100 34 68 0 1549806032 382916592 9019680
12:07:44 11206 8192000000 10368603776 10836946944 1157189056 12.377 100 40 67 1 1482463600 316544384 8730160
12:09:01 20772 8192000000 10237776560 10734215168 1133727296 12.156 99 18 34 1 1371086624 233422464 3936864
12:10:23 37820 8192000000 10141057856 10672934912 1112516416 11.956 98 17 32 0 1246403760 130585104 3302240
12:12:52 11846 8192000000 10110424288 10537345024 1136574048 12.183 99 46 81 3 1203675712 59401104 7700560
12:14:05 10519 8192000000 10128518400 10551386112 1135023680 12.169 99 57 110 3 1223425696 75967232 12434784
12:15:19 38380 8192000000 10092515728 10605912064 1136888432 12.186 100 17 34 0 1226268528 85584704 3795392
12:17:04 104750 8192000000 10046269856 10487267328 1092273728 11.764 96 13 26 0 1127153328 31810336 3069264
12:19:50 64191 8192000000 10115989744 10558902272 1111261872 11.944 98 34 64 1 1179114672 60990272 6862528
12:21:55 52019 8192000000 10080508976 10549567488 1122624864 12.052 99 14 30 0 1204311568 78843232 2843472
12:23:50 87329 8192000000 10155885744 10580664320 1142667536 12.241 99 32 64 0 1231139008 82255648 6215824
12:26:18 111399 8192000000 10050666352 10517356544 1038098480 11.246 91 7 14 0 1103556928 63879376 1579072
12:29:17 36333 8192000000 10124184752 10559262720 1115575680 11.985 98 19 37 0 1202815504 83022880 4216944
12:30:55 128280 8192000000 10066066208 10495320064 1109833184 11.931 98 14 26 2 1147572576 35442496 2296896
12:34:08 52335 8192000000 10092474416 10524106752 1122295168 12.049 99 44 86 0 1156191216 24976416 8919632
12:36:01 80647 8192000000 10094452832 10518396928 1087075616 11.715 96 41 80 1 1164194448 68188592 8930240
12:38:24 26844 8192000000 10119537072 10546655232 1136148784 12.179 98 45 92 0 1207613552 61118784 10345984
12:39:59 17835 8192000000 10208041936 10643910656 1163036032 12.432 100 41 82 0 1309577440 137220240 9321168
12:41:26 82979 8192000000 10259138656 10730532864 1167342784 12.472 100 26 52 0 1349304464 175537520 6424160
12:43:51 55559 8192000000 10245027312 10708799488 1167637728 12.475 100 28 56 1 1352914320 178437632 6838960
12:45:49 71355 8192000000 10184111680 10658832384 1145617680 12.268 98 21 41 0 1297679040 147378192 4683168
12:48:02 47620 8192000000 10171051392 10690781184 1148071744 12.291 99 16 32 0 1257807088 106251696 3483648
12:49:56 25588 8192000000 10139544544 10574712832 1161101088 12.414 99 30 60 1 1218721280 51353952 6266240
12:51:22 32179 8192000000 10183661072 10669633536 1156245984 12.368 99 23 44 0 1270707424 109556272 4905168
12:53:08 53158 8192000000 10069962880 10496385024 1088146208 11.725 94 32 62 1 1119181552 23459728 7575616
12:55:02 43709 8192000000 10200461504 10673356800 1155696608 12.363 100 35 71 0 1251824544 88620384 7507552
12:56:55 47396 8192000000 10240107008 10712637440 1152112256 12.329 99 26 52 0 1310909024 153192832 5603936
12:58:44 143489 8192000000 10088386912 10519756800 1121256704 12.039 98 40 73 1 1205389664 75473216 8659744
13:02:07 86944 8192000000 10063202208 10528509952 1113037568 11.961 97 19 36 0 1163583024 47283392 3262064
13:04:35 60998 8192000000 10067540192 10513731584 1112682496 11.958 98 20 39 2 1163765952 47290784 3792672
13:06:41 71606 8192000000 10064006656 10530865152 1115686624 11.986 98 10 20 0 1160502016 42664032 2151360
13:09:04 38623 8192000000 10048221232 10489315328 1110242688 11.935 97 28 52 0 1146170880 29986992 5941200
13:11:14 82613 8192000000 10075442592 10565545984 1106412800 11.898 97 13 26 0 1168268432 59273488 2582144
13:13:37 25163 8192000000 10057906816 10507649024 1110507328 11.937 98 27 54 0 1154697104 38726432 5463344
13:15:02 15838 8192000000 10088410144 10518474752 1111301216 11.945 99 46 92 0 1170983872 50005440 9677216
13:16:19 31755 8192000000 10165018880 10599616512 1123096032 12.056 99 55 110 0 1238484464 104467792 10920640
13:17:57 26241 8192000000 10146559120 10597756928 1120937104 12.036 99 38 76 0 1222163104 93562000 7664000
13:19:27 27550 8192000000 10079505088 10520457216 1135676400 12.175 100 46 89 0 1177671872 32953664 9041808
13:21:07 23458 8192000000 10121851072 10561597440 1128365136 12.106 99 37 75 0 1211598912 76013792 7219984
13:22:36 52680 8192000000 10149612720 10597335040 1109163776 11.924 98 32 63 0 1215947520 100049360 6734384
13:24:36 72331 8192000000 10081144688 10524749824 1114476864 11.975 98 14 28 0 1206625920 89138240 3010816
13:26:51 89939 8192000000 10057025200 10503569408 1116945504 11.998 99 27 52 0 1148828160 26386144 5496512
13:29:23 41324 8192000000 10076723616 10533556224 1126736512 12.091 100 31 63 0 1201709664 68600096 6373056
13:31:10 72290 8192000000 10061003248 10536964096 1116857584 11.997 99 7 14 0 1159788544 41093024 1837936
13:33:30 73449 8192000000 10099341376 10538459136 1118610432 12.014 99 28 52 0 1178506160 53548736 6346992
13:35:44 49253 8192000000 10090381904 10534158336 1119690336 12.024 99 24 48 0 1190281792 65715088 4876368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-02-2023 13:38:51 to 18-02-2023 13:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 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 18-02-2023 13:39:09 to 18-02-2023 13:39:11.

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          212198
MON$OLDEST_ACTIVE               213329
MON$OLDEST_SNAPSHOT             213329
MON$NEXT_TRANSACTION            213329
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                       2065568
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

18.02.23 13:39:12. 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-02-18 13:39:15 No severe PSQL-related problems occured
18.02.23 13:39:16. End of report.