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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15989176 kB
MemAvailable 31220912 kB
Buffers 0 kB
Cached 15750112 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-06-30 10:37:36
DTS_END                         2022-06-30 10:37:18.7950
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/53424
TRN_ID                          222483
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: 8894.36 29224 1067323
customer order: creation 161.78 449 19414
customer order: refuse 14.37 389 1724
order to supplier: creation 45.24 1331 5429
order to supplier: removal 3.25 160649 390
invoice (draft): creation 43.95 3429 5274
invoice (draft): removal 50.79 963 6095
invoice accept: apply 40.44 25753 4853
invoice accept: cancel 45.83 1202 5500
customer reserve: creation 3749.63 244 449956
customer reserve: removal 4450.81 91 534097
realization accept: apply 80.59 280 9671
realization accept: cancel 81.10 161 9732
payment from customer: creation 58.79 168 7055
payment from customer: removal 5.83 136 700
payment to supplier: creation 46.88 185 5625
payment to supplier: removal 3.38 147 405
service: total inventory turnovers 3.77 4335 452
service: total monetary turnovers 6.98 385 838
service: aggregate perf. data 0.90 16005 108
service: refresh index statistics 0.04 368168 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-06-2022 10:39:12 to 30-06-2022 10:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6629
TEST_TIME 2 6919
TEST_TIME 3 7616
TEST_TIME 4 7258
TEST_TIME 5 6987
TEST_TIME 6 6763
TEST_TIME 7 6644
TEST_TIME 8 6850
TEST_TIME 9 6982
TEST_TIME 10 7217
TEST_TIME 11 7193
TEST_TIME 12 7208
TEST_TIME 13 7459
TEST_TIME 14 7577
TEST_TIME 15 7841
TEST_TIME 16 7991
TEST_TIME 17 8016
TEST_TIME 18 8057
TEST_TIME 19 8189
TEST_TIME 20 8218
TEST_TIME 21 8279
TEST_TIME 22 8314
TEST_TIME 23 8731
TEST_TIME 24 9023
TEST_TIME 25 9097
TEST_TIME 26 9227
TEST_TIME 27 9032
TEST_TIME 28 9157
TEST_TIME 29 9655
TEST_TIME 30 9938
TEST_TIME 31 10598
TEST_TIME 32 10990
TEST_TIME 33 11115
TEST_TIME 34 11029
TEST_TIME 35 11027
TEST_TIME 36 10948
TEST_TIME 37 10888
TEST_TIME 38 10856
TEST_TIME 39 10688
TEST_TIME 40 10625
TEST_TIME 41 10499
TEST_TIME 42 10437
TEST_TIME 43 10312
TEST_TIME 44 10170
TEST_TIME 45 10082
TEST_TIME 46 10071
TEST_TIME 47 10245
TEST_TIME 48 10272
TEST_TIME 49 10246
TEST_TIME 50 10220
TEST_TIME 51 10172
TEST_TIME 52 10212
TEST_TIME 53 10040
TEST_TIME 54 9865
TEST_TIME 55 9835
TEST_TIME 56 9731
TEST_TIME 57 9646
TEST_TIME 58 9602
TEST_TIME 59 9665
TEST_TIME 60 9600
TEST_TIME 61 9530
TEST_TIME 62 9495
TEST_TIME 63 9497
TEST_TIME 64 9446
TEST_TIME 65 9348
TEST_TIME 66 9328
TEST_TIME 67 9310
TEST_TIME 68 9257
TEST_TIME 69 9206
TEST_TIME 70 9189
TEST_TIME 71 9124
TEST_TIME 72 9112
TEST_TIME 73 9015
TEST_TIME 74 9033
TEST_TIME 75 9042
TEST_TIME 76 8996
TEST_TIME 77 9031
TEST_TIME 78 9041
TEST_TIME 79 9035
TEST_TIME 80 9042
TEST_TIME 81 9047
TEST_TIME 82 9114
TEST_TIME 83 9133
TEST_TIME 84 9159
TEST_TIME 85 9181
TEST_TIME 86 9235
TEST_TIME 87 9221
TEST_TIME 88 9176
TEST_TIME 89 9290
TEST_TIME 90 9289
TEST_TIME 91 9196
TEST_TIME 92 9155
TEST_TIME 93 9157
TEST_TIME 94 9115
TEST_TIME 95 9070
TEST_TIME 96 9059
TEST_TIME 97 8999
TEST_TIME 98 8960
TEST_TIME 99 8972
TEST_TIME 100 8941
TEST_TIME 101 8917
TEST_TIME 102 8882
TEST_TIME 103 8891
TEST_TIME 104 8897
TEST_TIME 105 8904
TEST_TIME 106 8902
TEST_TIME 107 8909
TEST_TIME 108 8895
TEST_TIME 109 8941
TEST_TIME 110 8967
TEST_TIME 111 8998
TEST_TIME 112 9006
TEST_TIME 113 9019
TEST_TIME 114 9019
TEST_TIME 115 9062
TEST_TIME 116 9007
TEST_TIME 117 9010
TEST_TIME 118 8956
TEST_TIME 119 8955
TEST_TIME 120 8921

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-06-2022 10:39:16 to 30-06-2022 10:39:19.

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 1481751 1481751 0 0.00 0 35844 12 0 0
doc_list_biud 1481751 1481751 0 0.00 0 100766 38 0 0
sp_add_invoice_to_stock 4990 4853 137 2.74 53 186934 25753 0 137
sp_cancel_adding_invoice 5500 5500 0 0.00 6 97713 1203 0 0
sp_cancel_client_order 1724 1724 0 0.00 6 4985 389 0 0
sp_cancel_customer_reserve 534097 534097 0 0.00 1 21901 91 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 5801 137 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 4774 147 0 0
sp_cancel_supplier_invoice 6111 6095 16 0.26 2 100780 964 0 16
sp_cancel_supplier_order 390 390 0 0.00 7 1336522 160650 0 0
sp_cancel_write_off 9732 9732 0 0.00 2 14757 162 0 0
sp_client_order 19414 19414 0 0.00 5 45663 450 0 0
sp_customer_reserve 449956 449956 0 0.00 1 113031 244 0 0
sp_fill_shopping_cart 30109 30109 0 0.00 1 23781 88 0 0
sp_fill_shopping_cart_clo_res 449907 449907 0 0.00 0 15326 21 0 0
sp_get_clo_for_invoice 4800 4800 0 0.00 0 29888 355 0 0
sp_kill_cost_storno 16337 16337 0 0.00 0 35838 78 0 0
sp_kill_qstorno_ret_qs2qd 1084411 1084411 0 0.00 0 100763 39 0 0
sp_kill_qty_storno 572005 572005 0 0.00 0 100765 92 0 0
sp_lock_selected_doc 20138 20138 0 0.00 0 6135 8 0 0
sp_make_cost_storno 27147 27147 0 0.00 0 22830 76 0 0
sp_multiply_rows_for_pdistr 27147 27147 0 0.00 0 10673 39 0 0
sp_multiply_rows_for_qdistr 19414 19414 0 0.00 0 19911 163 0 0
sp_pay_from_customer 7055 7055 0 0.00 5 11701 168 0 0
sp_pay_to_supplier 5625 5625 0 0.00 7 10713 186 0 0
sp_payment_common 12680 12680 0 0.00 2 11671 46 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 4330 315 0 0
sp_qd_handle_on_invoice_upd_sts 10296 10296 0 0.00 0 35143 528 0 0
sp_qd_handle_on_reserve_upd_sts 9671 9671 0 0.00 0 14720 62 0 0
sp_reserve_write_off 9671 9671 0 0.00 9 16351 281 0 0
sp_supplier_invoice 5274 5274 0 0.00 10 123086 3430 0 0
sp_supplier_order 5429 5429 0 0.00 8 35225 1332 0 0
srv_aggregate_perf_data 108 108 0 0.00 2370 183792 16006 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 621 171687 40990 0 0
srv_find_qd_qs_mism 1020014 1020014 0 0.00 0 10369 9 0 0
srv_make_invnt_saldo 452 452 0 0.00 60 127426 4335 0 0
srv_make_money_saldo 838 838 0 0.00 1 8373 386 0 0
srv_recalc_idx_stat 5 5 0 0.00 250185 543120 368168 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4854 4854 0 0.00 0 21741 878 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 2 8503 470 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 1324 25 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1050 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 880 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 363 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 817 35 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 101153 6805 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1050 20 0 0
t$perf-norm:sp_client_order 19414 19414 0 0.00 0 15762 36 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 2010 17 0 0
t$perf-norm:sp_pay_from_customer 7055 7055 0 0.00 0 6177 20 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 8855 22 0 0
t$perf-norm:sp_reserve_write_off 9671 9671 0 0.00 0 8507 25 0 0
t$perf-norm:sp_supplier_invoice 5274 5274 0 0.00 0 11383 30 0 0
t$perf-norm:sp_supplier_order 5429 5429 0 0.00 0 5057 31 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 353 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 39 4 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 1056 18 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 321 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 199 80 0 0
v_add_invoice_to_stock 9866 9592 274 2.77 0 3025 25 0 274
v_all_customers 36006 36006 0 0.00 0 341 4 0 0
v_all_suppliers 29782 29782 0 0.00 0 94 4 0 0
v_all_wares 38828 38828 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 2279 17 0 0
v_cancel_client_order 3448 3448 0 0.00 0 3902 13 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2189 14 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 504 11 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1776 12 0 0
v_cancel_supplier_invoice 800 768 32 4.00 0 3405 28 0 32
v_cancel_supplier_order 780 780 0 0.00 0 447 10 0 0
v_cancel_write_off 2036 2036 0 0.00 0 3347 29 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 2668 6 0 0
v_max_id_clo_res 966 966 0 0.00 0 1527 11 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 7008 8 0 0
v_max_non_paid_invoice 1429 1429 0 0.00 0 525 9 0 0
v_max_non_paid_realizn 3625 3625 0 0.00 0 752 8 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 13219 13 0 0
v_min_id_clo_res 966 966 0 0.00 0 2049 16 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 3672 9 0 0
v_min_non_paid_invoice 5625 5625 0 0.00 0 6794 19 0 0
v_min_non_paid_realizn 7055 7055 0 0.00 0 8113 18 0 0
v_our_firm 9682 9682 0 0.00 0 70 4 0 0
v_reserve_write_off 19342 19342 0 0.00 0 5117 12 0 0
x_lock_depdocs_on_canc_invoice 11211 11211 0 0.00 0 20152 129 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 6850 379 0 0
x_make_qstorno_1000_1200 5429 5429 0 0.00 2 35208 1151 0 0
x_make_qstorno_1200_2000 5274 5274 0 0.00 3 123023 3193 0 0
x_make_qstorno_2100_3300 449956 449956 0 0.00 0 113029 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-06-2022 10:39:20 to 30-06-2022 10:39:24.

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 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 28190 8192000000 9721481424 10020995072 796317232 8.859 100 83 166 0 824381824 7426480 20638112
08:09:52 15297 8192000000 9765673456 10072875008 837765680 9.277 100 89 178 0 871051008 12202368 21082960
08:11:12 13499 8192000000 9794566032 10112397312 862939504 9.530 100 90 176 0 901137760 17952256 20246000
08:12:27 7738 8192000000 9852780384 10182254592 905781440 9.956 100 80 158 1 961840688 38713520 17345728
08:13:38 3929 8192000000 9908833056 10250534912 945404896 10.346 100 84 164 0 1029429776 66153136 17871744
08:14:47 3110 8192000000 9951056752 10302078976 991006112 10.791 100 88 172 0 1065309584 56962528 17340944
08:15:52 1541 8192000000 9983800544 10346065920 1016700912 11.040 100 91 179 0 1105438752 69897040 18840800
08:16:55 1419 8192000000 10005667632 10373545984 1035405296 11.220 100 86 157 0 1128320352 78320528 14594528
08:17:58 1195 8192000000 10024831840 10398035968 1059743680 11.454 100 95 183 1 1145083616 66393456 18946480
08:19:02 949 8192000000 10035709696 10410586112 1068587440 11.539 100 94 178 1 1155272752 69516160 17169152
08:20:04 1568 8192000000 10045365152 10429153280 1072532192 11.576 100 85 164 3 1170352128 80905744 16914192
08:21:09 1261 8192000000 10079746848 10462109696 1074759008 11.598 100 99 191 1 1203699072 108081728 20858336
08:22:11 1085 8192000000 10060833968 10452271104 1082765952 11.674 100 93 183 0 1190884096 90372688 17745456
08:23:13 1109 8192000000 10048853200 10437533696 1085031824 11.695 100 98 192 4 1174077920 69497088 19549008
08:24:16 985 8192000000 10064637952 10451582976 1089678128 11.740 100 94 170 2 1185813616 78422896 17712592
08:25:18 1005 8192000000 10048494768 10439872512 1094608512 11.786 100 92 181 0 1176461040 62905920 18946608
08:26:20 1404 8192000000 10050124288 10446024704 1091603776 11.758 100 79 150 1 1171357840 64381040 15373024
08:27:24 1044 8192000000 10060999424 10444460032 1094605424 11.786 100 90 166 0 1182524176 71236912 16681840
08:28:26 1036 8192000000 10052514832 10438836224 1087133792 11.715 100 90 174 0 1173695152 68793184 17768176
08:29:27 1278 8192000000 10049369424 10431229952 1076959280 11.618 100 99 191 3 1169244432 73620864 18664288
08:30:29 913 8192000000 10011700400 10392096768 1060960640 11.466 99 89 168 2 1135224576 57704496 16559440
08:31:31 906 8192000000 10008973696 10386403328 1047418432 11.336 100 88 162 1 1131537088 67148160 16970496
08:32:33 786 8192000000 10016531312 10400935936 1067356752 11.527 100 83 158 1 1139050096 55441872 16251472
08:33:35 1510 8192000000 10014169568 10392010752 1055048448 11.409 100 95 176 3 1136013648 63917616 17047584
08:34:37 1132 8192000000 10008574480 10390794240 1058283632 11.440 100 96 180 1 1134965520 56253984 20427904
08:35:39 1358 8192000000 10003432176 10375147520 1038197648 11.247 100 92 179 0 1121499904 65054336 18247920
08:36:43 1394 8192000000 9996107728 10372931584 1041567808 11.280 100 81 160 1 1113745904 55636704 16541392
08:37:48 13014 8192000000 10018957344 10410856448 1056322992 11.421 100 64 121 1 1130709776 62713408 11673376
08:39:12 3062 8192000000 10024675920 10411384832 1068438432 11.537 100 70 133 4 1150375600 68468272 13468896
08:40:17 1444 8192000000 10064846864 10450767872 1079743712 11.645 100 90 171 2 1182033328 84137840 18151776
08:41:28 993 8192000000 10098237760 10481827840 1097792032 11.817 100 100 198 0 1215175376 97119936 20263408
08:42:34 3476 8192000000 10109057952 10499809280 1101211424 11.849 100 73 141 1 1222935920 106546560 15177936
08:43:39 4862 8192000000 10141590656 10535092224 1115618304 11.986 100 74 140 1 1260998208 131023376 14356528
08:44:51 8753 8192000000 10172252832 10576945152 1120029472 12.027 100 66 127 1 1290584336 156255648 14299216
08:46:07 3908 8192000000 10213765536 10622464000 1134442960 12.163 100 72 144 1 1330555648 180814944 15297744
08:47:16 973 8192000000 10249990032 10653925376 1147046848 12.282 100 93 176 1 1363156848 196004496 20105504
08:48:22 1225 8192000000 10273331520 10673958912 1166511696 12.464 100 82 160 0 1388697104 204348976 17836432
08:49:28 1538 8192000000 10304698624 10711162880 1166898384 12.468 100 90 174 2 1418574128 231140416 20535328
08:50:33 1066 8192000000 10325495664 10729943040 1167440576 12.473 100 91 173 1 1437616336 250420608 19755152
08:51:37 1565 8192000000 10352247584 10763386880 1160036352 12.404 100 96 185 0 1455326592 274580480 20709760
08:52:41 1184 8192000000 10348852000 10769285120 1175287344 12.546 100 92 178 1 1456691056 261447648 19956064
08:53:46 852 8192000000 10384472656 10790387712 1175666304 12.550 100 93 185 1 1492114864 294895024 21553536
08:54:49 1991 8192000000 10425419568 10835013632 1185869456 12.645 100 93 167 1 1529723328 323938112 19915760
08:55:54 1258 8192000000 10416927248 10850271232 1177920144 12.571 100 97 191 0 1525364304 325178768 22265392
08:56:58 1368 8192000000 10451914304 10880323584 1190820896 12.691 100 94 186 2 1561138032 347243904 23073232
08:58:01 769 8192000000 10485502640 10908614656 1179743264 12.588 100 97 187 0 1593500032 390043264 23713504
08:59:04 613 8192000000 10523931712 10945761280 1181483088 12.604 100 92 184 0 1629934352 425798224 22653040
09:00:06 722 8192000000 10540828400 11009163264 1193368320 12.715 100 95 188 0 1643509696 426929248 23212128
09:01:10 3108 8192000000 10535626288 10976337920 1199862656 12.775 100 71 136 0 1643865424 425973088 18029680
09:02:32 3461 8192000000 10522928256 10965925888 1195352592 12.733 100 50 98 0 1622357232 413403152 13601488
09:03:55 18809 8192000000 10548023424 10989273088 1195158448 12.731 100 40 80 0 1645637744 440143840 10335456
09:06:10 5798 8192000000 10478499968 10996305920 1149451504 12.304 99 33 66 0 1573045552 414099376 9494672
09:07:18 13685 8192000000 10403619888 10906632192 1159247584 12.396 100 33 66 0 1535535888 367761616 8526688
09:08:32 40228 8192000000 10238957136 10687995904 1144361296 12.257 99 26 52 0 1375292848 224382048 6549504
09:10:13 46765 8192000000 10187139040 10630217728 1146713888 12.279 99 26 50 0 1284491696 131513824 6263984
09:12:10 26128 8192000000 10201282496 10661408768 1160204912 12.405 100 29 56 0 1305862544 138982992 6674640
09:13:49 60749 8192000000 10135602528 10651336704 1128416832 12.106 98 17 34 0 1227863840 95382784 4064224
09:15:52 12860 8192000000 10094963088 10529710080 1148929472 12.299 100 74 140 0 1186477120 21740128 15807520
09:17:12 59427 8192000000 10084200880 10528690176 1100701104 11.844 96 25 48 1 1129883040 24165472 5016464
09:19:12 77649 8192000000 10072434448 10527010816 1085689008 11.702 95 29 54 0 1136420864 46023104 4708752
09:21:39 39540 8192000000 10136218896 10580262912 1105448144 11.889 97 40 80 0 1197054464 82881680 8724640
09:23:29 82124 8192000000 10113152016 10615156736 1129838272 12.120 100 9 18 1 1223526144 91770096 1917776
09:25:52 107847 8192000000 10064607808 10537291776 1088406096 11.728 96 8 15 0 1115920304 26419520 1094688
09:28:41 95087 8192000000 10060522064 10506362880 1074864752 11.599 94 16 31 0 1124592384 46781088 2946544
09:31:57 11694 8192000000 10081731520 10518614016 1127075488 12.094 99 60 111 0 1169076768 30158304 11842976
09:33:12 171686 8192000000 10076961296 10530656256 1027504720 11.144 90 20 38 1 1067978496 36924032 3549744
09:38:15 95572 8192000000 10087938400 10584915968 1137296544 12.190 100 7 14 0 1198669632 59966016 1407072
09:40:56 93629 8192000000 10047416496 10516242432 1102256080 11.859 97 24 48 1 1160123792 52592160 5275552
09:43:43 83787 8192000000 10067559440 10573590528 1135401328 12.172 100 7 14 0 1183019648 46421616 1196704
09:46:16 59443 8192000000 10079597968 10552410112 1120822096 12.035 98 15 30 0 1163875712 39753232 3300384
09:48:20 75203 8192000000 10044351312 10490810368 1066806064 11.522 94 23 46 0 1107669568 36410176 4453328
09:50:39 77523 8192000000 10083610736 10574049280 1108177040 11.915 98 21 42 0 1169964192 57157072 4630080
09:52:57 38918 8192000000 10039857824 10500116480 1115251904 11.982 99 22 37 0 1145278624 26681120 3345600
09:54:36 64273 8192000000 10074820208 10535952384 1089391104 11.737 96 15 28 0 1166137024 73596768 3149152
09:56:58 49299 8192000000 10097625776 10535530496 1137548272 12.192 100 22 44 0 1186508928 44941168 4019488
09:58:55 30146 8192000000 10122462784 10573529088 1130021056 12.122 99 37 74 0 1228144400 90826576 7296768
10:00:26 78264 8192000000 10108297360 10566995968 1113516624 11.966 97 28 55 0 1200261984 81205440 5539920
10:02:45 24197 8192000000 10095108832 10543054848 1117309920 12.002 98 40 80 0 1188557376 62385920 8861536
10:04:31 34048 8192000000 10124397840 10549993472 1136925984 12.187 99 37 72 0 1205889984 60478256 8485744
10:06:21 76458 8192000000 10122116080 10647695360 1134747968 12.166 100 23 42 0 1272763888 133541056 4474864
10:08:42 68436 8192000000 10088517856 10542100480 1130639904 12.127 99 33 66 0 1197692320 60302224 6750192
10:10:51 74799 8192000000 10045844128 10498375680 1087261632 11.717 95 17 32 0 1125462304 34736464 3464208
10:13:06 81897 8192000000 10080844656 10551238656 1111510416 11.947 98 10 20 0 1157863808 44297200 2056192
10:15:54 78960 8192000000 10062715872 10531024896 1111108032 11.943 98 12 22 1 1149899104 36672944 2118128
10:18:14 41300 8192000000 10100419136 10550140928 1120739680 12.034 98 30 58 1 1187088384 60292704 6056000
10:19:57 87790 8192000000 10068581536 10511900672 1122016576 12.046 99 14 26 0 1166497136 41147344 3333216
10:22:28 22165 8192000000 10073142576 10527334400 1119706720 12.024 99 23 44 1 1180641536 56598416 4336400
10:23:55 61661 8192000000 10109655456 10589589504 1098501312 11.823 97 33 66 0 1182709168 77307824 6900032
10:26:29 30609 8192000000 10084774544 10560503808 1104110928 11.877 98 34 68 0 1182480176 71846608 6522640
10:28:02 46367 8192000000 10055841936 10504654848 1092771056 11.769 97 23 46 0 1138083952 41005088 4307808
10:29:51 62877 8192000000 10073600224 10549653504 1080129952 11.649 96 39 76 0 1147238816 59871568 7237296
10:31:56 78011 8192000000 10076480912 10507558912 1047897104 11.341 93 17 34 0 1072239216 20976768 3365344
10:34:16 158130 8192000000 10084880544 10542321664 1106161840 11.896 98 37 74 1 1228371776 113431568 8778368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-06-2022 10:39:26 to 30-06-2022 10:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-06-2022 10:39:43 to 30-06-2022 10:39:45.

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


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

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