Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.02.2023 04: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 14714532 kB
MemAvailable 30362688 kB
Buffers 20 kB
Cached 16896364 kB
SwapCached 3140 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-22 04:38:03
DTS_END                         2023-02-22 04:37:28.1820
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/55868
TRN_ID                          227181
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: 9252.91 25172 1110349
customer order: creation 177.91 473 21349
customer order: refuse 15.68 403 1882
order to supplier: creation 47.29 1374 5675
order to supplier: removal 3.46 160085 415
invoice (draft): creation 46.68 3239 5601
invoice (draft): removal 51.63 1041 6196
invoice accept: apply 43.36 25184 5203
invoice accept: cancel 46.23 1271 5547
customer reserve: creation 3992.13 240 479055
customer reserve: removal 4528.30 94 543396
realization accept: apply 84.91 290 10189
realization accept: cancel 80.62 165 9674
payment from customer: creation 60.73 184 7288
payment from customer: removal 6.06 133 727
payment to supplier: creation 51.10 204 6132
payment to supplier: removal 3.62 115 434
service: total inventory turnovers 4.07 4194 488
service: total monetary turnovers 8.12 375 974
service: aggregate perf. data 1.00 15206 120
service: refresh index statistics 0.03 289176 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-02-2023 04:38:32 to 22-02-2023 04:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9119
TEST_TIME 2 7646
TEST_TIME 3 6016
TEST_TIME 4 5779
TEST_TIME 5 5882
TEST_TIME 6 6166
TEST_TIME 7 6033
TEST_TIME 8 6150
TEST_TIME 9 6401
TEST_TIME 10 6601
TEST_TIME 11 6940
TEST_TIME 12 6896
TEST_TIME 13 6969
TEST_TIME 14 7485
TEST_TIME 15 7737
TEST_TIME 16 7897
TEST_TIME 17 8099
TEST_TIME 18 8121
TEST_TIME 19 8045
TEST_TIME 20 7977
TEST_TIME 21 7893
TEST_TIME 22 7891
TEST_TIME 23 7978
TEST_TIME 24 8165
TEST_TIME 25 8283
TEST_TIME 26 8292
TEST_TIME 27 8351
TEST_TIME 28 8591
TEST_TIME 29 9146
TEST_TIME 30 9753
TEST_TIME 31 9718
TEST_TIME 32 10229
TEST_TIME 33 10302
TEST_TIME 34 10646
TEST_TIME 35 10682
TEST_TIME 36 10901
TEST_TIME 37 10755
TEST_TIME 38 10772
TEST_TIME 39 10821
TEST_TIME 40 10745
TEST_TIME 41 10617
TEST_TIME 42 10518
TEST_TIME 43 10434
TEST_TIME 44 10425
TEST_TIME 45 10379
TEST_TIME 46 10265
TEST_TIME 47 10102
TEST_TIME 48 9952
TEST_TIME 49 9847
TEST_TIME 50 10173
TEST_TIME 51 10019
TEST_TIME 52 9902
TEST_TIME 53 9991
TEST_TIME 54 9921
TEST_TIME 55 9874
TEST_TIME 56 9825
TEST_TIME 57 9953
TEST_TIME 58 9835
TEST_TIME 59 9747
TEST_TIME 60 9748
TEST_TIME 61 9679
TEST_TIME 62 9690
TEST_TIME 63 9741
TEST_TIME 64 9670
TEST_TIME 65 9685
TEST_TIME 66 9812
TEST_TIME 67 9752
TEST_TIME 68 9739
TEST_TIME 69 9743
TEST_TIME 70 9925
TEST_TIME 71 9862
TEST_TIME 72 9860
TEST_TIME 73 9833
TEST_TIME 74 9792
TEST_TIME 75 9791
TEST_TIME 76 9672
TEST_TIME 77 9703
TEST_TIME 78 9620
TEST_TIME 79 9556
TEST_TIME 80 9549
TEST_TIME 81 9459
TEST_TIME 82 9436
TEST_TIME 83 9467
TEST_TIME 84 9452
TEST_TIME 85 9393
TEST_TIME 86 9394
TEST_TIME 87 9397
TEST_TIME 88 9357
TEST_TIME 89 9344
TEST_TIME 90 9392
TEST_TIME 91 9352
TEST_TIME 92 9399
TEST_TIME 93 9488
TEST_TIME 94 9521
TEST_TIME 95 9479
TEST_TIME 96 9520
TEST_TIME 97 9555
TEST_TIME 98 9516
TEST_TIME 99 9536
TEST_TIME 100 9474
TEST_TIME 101 9412
TEST_TIME 102 9379
TEST_TIME 103 9358
TEST_TIME 104 9320
TEST_TIME 105 9295
TEST_TIME 106 9302
TEST_TIME 107 9266
TEST_TIME 108 9196
TEST_TIME 109 9255
TEST_TIME 110 9228
TEST_TIME 111 9199
TEST_TIME 112 9225
TEST_TIME 113 9204
TEST_TIME 114 9179
TEST_TIME 115 9233
TEST_TIME 116 9290
TEST_TIME 117 9277
TEST_TIME 118 9263
TEST_TIME 119 9260
TEST_TIME 120 9287

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-02-2023 04:38:36 to 22-02-2023 04:38:39.

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 1551699 1551699 0 0.00 0 16835 13 0 0
doc_list_biud 1551683 1551683 0 0.00 0 54704 39 0 0
sp_add_invoice_to_stock 5261 5203 58 1.10 27 214474 25185 0 58
sp_cancel_adding_invoice 5547 5547 0 0.00 7 76698 1272 0 0
sp_cancel_client_order 1882 1882 0 0.00 6 14045 403 0 0
sp_cancel_customer_reserve 543396 543396 0 0.00 1 54720 95 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 4 4791 133 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 4 1654 116 0 0
sp_cancel_supplier_invoice 6200 6196 4 0.06 3 34838 1042 0 4
sp_cancel_supplier_order 415 415 0 0.00 7 1473451 160085 0 0
sp_cancel_write_off 9674 9674 0 0.00 2 12995 166 0 0
sp_client_order 21349 21349 0 0.00 6 30789 473 0 0
sp_customer_reserve 479055 479055 0 0.00 0 46453 241 0 0
sp_fill_shopping_cart 32617 32617 0 0.00 1 15332 83 0 0
sp_fill_shopping_cart_clo_res 479013 479013 0 0.00 0 16144 17 0 0
sp_get_clo_for_invoice 5150 5150 0 0.00 0 19474 234 0 0
sp_kill_cost_storno 16382 16382 0 0.00 0 13559 74 0 0
sp_kill_qstorno_ret_qs2qd 1103077 1103077 0 0.00 0 54682 41 0 0
sp_kill_qty_storno 582446 582446 0 0.00 0 54702 96 0 0
sp_lock_selected_doc 21471 21471 0 0.00 0 11242 9 0 0
sp_make_cost_storno 28759 28759 0 0.00 0 16824 84 0 0
sp_multiply_rows_for_pdistr 28756 28756 0 0.00 0 11223 39 0 0
sp_multiply_rows_for_qdistr 21349 21349 0 0.00 0 10459 171 0 0
sp_pay_from_customer 7288 7288 0 0.00 5 15863 185 0 0
sp_pay_to_supplier 6132 6132 0 0.00 5 19687 204 0 0
sp_payment_common 13420 13420 0 0.00 2 10540 47 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 13971 325 0 0
sp_qd_handle_on_invoice_upd_sts 10696 10696 0 0.00 0 28216 533 0 0
sp_qd_handle_on_reserve_upd_sts 10187 10187 0 0.00 0 20162 68 0 0
sp_reserve_write_off 10189 10189 0 0.00 8 20259 290 0 0
sp_supplier_invoice 5601 5601 0 0.00 9 103151 3239 0 0
sp_supplier_order 5675 5675 0 0.00 9 38377 1374 0 0
srv_aggregate_perf_data 120 120 0 0.00 2397 203880 15207 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 738 153993 32170 0 0
srv_find_qd_qs_mism 1059793 1059793 0 0.00 0 10520 10 0 0
srv_make_invnt_saldo 488 488 0 0.00 95 153127 4195 0 0
srv_make_money_saldo 974 974 0 0.00 1 16100 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 30300 591869 289176 0 0
t$perf-abend:sp_add_invoice_to_stock 58 58 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5212 5212 0 0.00 1 8718 899 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 2 8503 451 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 3970 31 0 0
t$perf-norm:sp_cancel_customer_reserve 750 750 0 0.00 0 2007 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 5137 33 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 669 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 641 31 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 83558 7506 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 2864 22 0 0
t$perf-norm:sp_client_order 21349 21349 0 0.00 0 5632 34 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 1415 16 0 0
t$perf-norm:sp_pay_from_customer 7288 7288 0 0.00 0 5678 22 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 3427 20 0 0
t$perf-norm:sp_reserve_write_off 10189 10189 0 0.00 0 4031 20 0 0
t$perf-norm:sp_supplier_invoice 5601 5601 0 0.00 0 2532 28 0 0
t$perf-norm:sp_supplier_order 5676 5676 0 0.00 0 3898 30 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 800 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 77 4 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 1719 14 0 0
t$perf-norm:srv_make_money_saldo 974 974 0 0.00 0 578 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 181 60 0 0
v_add_invoice_to_stock 10412 10296 116 1.11 0 5954 28 0 116
v_all_customers 39276 39276 0 0.00 0 87 4 0 0
v_all_suppliers 31574 31574 0 0.00 0 140 4 0 0
v_all_wares 42698 42698 0 0.00 0 142 4 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 2785 22 0 0
v_cancel_client_order 3764 3764 0 0.00 0 2715 11 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 1986 13 0 0
v_cancel_customer_reserve 1500 1500 0 0.00 0 2839 16 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 1584 15 0 0
v_cancel_supplier_invoice 880 872 8 0.90 0 2192 26 0 8
v_cancel_supplier_order 830 830 0 0.00 0 2720 16 0 0
v_cancel_write_off 2088 2088 0 0.00 0 4964 31 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 4058 5 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 970 11 0 0
v_max_id_ord_sup 5595 5595 0 0.00 0 5197 8 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 572 8 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 1444 8 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 9094 11 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 5352 15 0 0
v_min_id_ord_sup 5595 5595 0 0.00 0 2059 7 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 5550 15 0 0
v_min_non_paid_realizn 7288 7288 0 0.00 0 8166 19 0 0
v_our_firm 10572 10572 0 0.00 0 52 5 0 0
v_reserve_write_off 20374 20374 0 0.00 0 3709 12 0 0
x_lock_depdocs_on_canc_invoice 11307 11307 0 0.00 0 21707 128 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 19792 442 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 2 38362 1204 0 0
x_make_qstorno_1200_2000 5601 5601 0 0.00 4 99458 3018 0 0
x_make_qstorno_2100_3300 479052 479052 0 0.00 0 45809 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-02-2023 04:38:40 to 22-02-2023 04:38:44.

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
02:07:31 921 8192000000 8930309824 8987029504 31583936 0.384 7 5 11 0 33020064 291024 1145104
02:08:33 15258 8192000000 9714806288 10011852800 795695040 8.853 100 84 164 0 822942160 7056128 20190992
02:09:56 23892 8192000000 9767429696 10077454336 840443600 9.304 100 88 176 0 873748880 12012288 21292992
02:11:27 12979 8192000000 9810637696 10130485248 877281904 9.673 100 82 164 0 915750432 19815840 18652688
02:12:50 11468 8192000000 9871363456 10205917184 917849616 10.075 100 85 168 0 982992816 46128032 19015168
02:14:04 4952 8192000000 9917208704 10260475904 956277264 10.453 100 86 170 0 1027671984 53139712 18255008
02:15:15 1947 8192000000 9965285088 10317529088 1002343088 10.901 100 95 188 3 1074831824 53426448 19062288
02:16:20 1269 8192000000 10015232752 10377986048 1024993344 11.120 100 94 184 1 1131823776 88795840 18034592
02:17:24 1949 8192000000 10003911152 10380980224 1038871792 11.254 100 91 178 1 1122122672 65609552 17641328
02:18:27 1376 8192000000 10042336896 10420228096 1061524112 11.471 100 93 175 2 1164521184 84879792 18117280
02:19:30 1070 8192000000 10049581376 10428985344 1072777616 11.579 100 97 190 3 1167792272 76215168 18799488
02:20:33 1360 8192000000 10041948656 10423455744 1079051872 11.638 100 91 167 1 1159726896 63092704 17582320
02:21:37 937 8192000000 10054324544 10439725056 1083962304 11.685 100 90 181 2 1175162832 72812464 18388064
02:22:39 1138 8192000000 10049771728 10437554176 1087729392 11.721 100 92 181 2 1166845232 60665440 18450400
02:23:43 1462 8192000000 10063042672 10453663744 1093738112 11.778 100 92 168 1 1187898080 75736160 18423808
02:24:46 1170 8192000000 10078504992 10472054784 1097240624 11.811 100 99 179 1 1199803776 84630352 17932800
02:25:48 1189 8192000000 10062792064 10460143616 1100393488 11.841 100 94 185 1 1192792688 73569216 18829984
02:26:50 1075 8192000000 10056943280 10447392768 1102175920 11.858 100 93 179 0 1177068576 55848656 19044000
02:27:52 4677 8192000000 10046509088 10441580544 1099525344 11.833 100 59 105 2 1168149600 58515952 10108304
02:28:57 3178 8192000000 10048220304 10450984960 1096126896 11.801 100 74 146 0 1178367168 67531808 14708464
02:30:02 1136 8192000000 10065753408 10451718144 1077384864 11.623 99 97 186 4 1187518224 92586992 17546368
02:31:04 973 8192000000 10033159392 10419429376 1070575600 11.558 100 95 170 2 1152194768 64729904 16889264
02:32:06 1074 8192000000 10026608368 10420641792 1078573440 11.634 100 77 143 1 1151590736 57840784 15176512
02:33:08 941 8192000000 10018691840 10398990336 1043946480 11.303 99 90 176 2 1139101888 76850464 18304944
02:34:11 1209 8192000000 10009562352 10390355968 1060234832 11.459 100 90 168 0 1134303024 57606032 16462160
02:35:13 1364 8192000000 10012651824 10392829952 1044977888 11.312 100 87 165 1 1139547296 77860816 16708592
02:36:15 1666 8192000000 9986474880 10367389696 1044520352 11.308 100 78 147 5 1106418992 45950192 15948448
02:37:18 1300 8192000000 9998032160 10372096000 1040509312 11.270 99 98 182 4 1119175568 59840864 18825392
02:38:25 13403 8192000000 10012132512 10401153024 1073220576 11.583 100 58 113 0 1130782672 46401936 11160160
02:40:08 12509 8192000000 10075465536 10462740480 1100538592 11.843 100 76 151 1 1193215024 77590096 15086336
02:41:26 10690 8192000000 10094899680 10495156224 1109583968 11.928 100 66 128 2 1224449648 100627536 14238144
02:42:45 2994 8192000000 10107097776 10507206656 1117269952 12.001 100 68 132 1 1220486240 89111824 14104464
02:43:58 20077 8192000000 10133771824 10542022656 1123780176 12.063 100 62 122 0 1247659840 111107664 12772000
02:45:23 2677 8192000000 10161554864 10557280256 1127599104 12.099 100 79 147 1 1284882928 142081008 15202816
02:46:28 1899 8192000000 10219802432 10617884672 1138327808 12.200 100 82 162 0 1327680496 170685072 18667616
02:47:34 2196 8192000000 10217051680 10622464000 1147299744 12.284 100 85 160 3 1329633888 165673168 16660976
02:48:38 2589 8192000000 10244829312 10647310336 1150489840 12.314 100 87 170 3 1355424000 186597584 18336576
02:49:44 1556 8192000000 10281987424 10685829120 1157513008 12.380 100 95 183 2 1393063344 214831216 20719120
02:50:49 992 8192000000 10284337728 10709594112 1167319600 12.472 100 96 184 0 1399010192 209427072 22263520
02:51:54 874 8192000000 10276260848 10713423872 1174255680 12.537 100 93 177 2 1385071904 190501392 20314832
02:52:58 3199 8192000000 10285791808 10717241344 1184561424 12.633 100 81 157 0 1399594064 196648432 18384208
02:54:03 2241 8192000000 10282516224 10701512704 1180851248 12.598 100 78 151 2 1401280448 202685344 17743856
02:55:09 2140 8192000000 10344034496 10762788864 1189005392 12.674 100 82 161 1 1454914000 246980704 18927904
02:56:17 4812 8192000000 10322835296 10747174912 1155227664 12.359 98 70 133 0 1423559184 252236800 16094720
02:57:30 1693 8192000000 10376469200 10785554432 1182849312 12.617 100 84 163 0 1482366640 280494864 19022464
02:58:35 1081 8192000000 10435975632 10843033600 1185324112 12.640 100 92 181 2 1545280128 336944528 23011488
02:59:39 776 8192000000 10494570720 10901213184 1189568816 12.679 100 96 186 0 1600637280 387778576 23289888
03:00:41 727 8192000000 10516846096 10958741504 1189258064 12.676 100 94 186 0 1622513728 409306432 23949232
03:01:44 799 8192000000 10533979152 10963689472 1188992624 12.674 100 93 184 1 1636452736 424753696 22706416
03:02:47 1994 8192000000 10536474912 10955624448 1171524432 12.511 99 76 150 1 1640148208 449899376 18724400
03:03:51 2545 8192000000 10596701856 11029336064 1179982784 12.590 100 74 146 1 1705780640 506538800 19259056
03:05:00 931 8192000000 10569147792 11008372736 1152154272 12.330 99 86 165 1 1674214928 501233840 20826816
03:06:03 1819 8192000000 10477235616 10994827264 1149360000 12.303 100 72 140 0 1578012368 411262944 17389424
03:07:06 6385 8192000000 10352869744 10806444032 1148016688 12.291 100 48 95 1 1467731824 308637120 11078016
03:08:15 32650 8192000000 10347442752 10809630720 1120864816 12.035 98 26 52 0 1418516144 291375728 6275600
03:09:49 62512 8192000000 10174732544 10688860160 1093992512 11.781 97 18 36 0 1301689344 203324944 4371888
03:11:52 35259 8192000000 10111720080 10610745344 1131978768 12.140 100 18 36 0 1207709936 71663152 4068016
03:13:52 62295 8192000000 10068225536 10485719040 1116833728 11.997 98 41 67 2 1133557504 9312672 7411104
03:15:55 89382 8192000000 10075651856 10544201728 1084648080 11.692 95 18 34 0 1117804704 29571312 3585312
03:18:25 32082 8192000000 10066507696 10500816896 1124822144 12.073 99 38 71 1 1164268976 32154464 7292368
03:20:00 16276 8192000000 10085318928 10509045760 1142637120 12.240 100 45 83 0 1179852848 30201056 7014672
03:21:21 76634 8192000000 10067691520 10516267008 1087692208 11.721 95 13 26 0 1110632192 20608496 2331488
03:23:42 42219 8192000000 10144654704 10578038784 1149438448 12.304 100 15 30 0 1226579936 74135584 3005904
03:25:39 153992 8192000000 10125548096 10608627712 1146083120 12.273 100 23 44 0 1338366656 186344848 5938688
03:29:29 10168 8192000000 10105720400 10538864640 1133019824 12.150 98 43 85 1 1206202032 64708928 8473280
03:30:39 25070 8192000000 10103911728 10540478464 1123106128 12.056 98 31 57 0 1182820176 53915344 5798704
03:32:06 26660 8192000000 10159894352 10593705984 1153400624 12.341 100 44 81 2 1246876816 84793344 8682848
03:33:36 66066 8192000000 10100592256 10600017920 1132606448 12.146 99 10 20 0 1205799872 70793216 2400208
03:36:05 33120 8192000000 10104035520 10559115264 1107141136 11.905 97 17 32 0 1200960576 90487040 3332400
03:37:38 47577 8192000000 10085571216 10561609728 1138465904 12.201 100 11 20 0 1187617328 47217920 1933504
03:39:28 33524 8192000000 10119113104 10583351296 1142886256 12.243 100 18 36 0 1231318272 84599120 3832896
03:41:01 36093 8192000000 10121315680 10567970816 1130276736 12.124 98 18 36 0 1199151248 65059648 3814864
03:42:42 37593 8192000000 10129490560 10608455680 1128485232 12.107 99 24 48 0 1219337248 85648208 5203808
03:44:21 73889 8192000000 10146599712 10605920256 1139047216 12.207 99 29 57 0 1239427568 93984544 6395808
03:46:39 80870 8192000000 10067251200 10547372032 1085313904 11.698 94 11 20 0 1162846880 75456800 2076176
03:49:00 10568 8192000000 10079865680 10503323648 1122608976 12.052 99 55 99 0 1169955184 35983280 11362928
03:50:11 44854 8192000000 10079662656 10498625536 1110203744 11.934 98 42 83 0 1143830096 24758176 8868176
03:51:57 12909 8192000000 10116056096 10534412288 1100060400 11.838 98 52 102 1 1185848832 74815632 10972800
03:53:19 80390 8192000000 10071790832 10553188352 1116300320 11.992 100 51 102 2 1211102256 83856576 10945360
03:55:40 49060 8192000000 10061150064 10490748928 1075126224 11.601 96 39 78 0 1122423120 38493664 8803232
03:57:29 66905 8192000000 10119511536 10569621504 1108321664 11.917 99 14 28 0 1204493776 93385600 2786512
03:59:36 84209 8192000000 10095071648 10583478272 1110709296 11.939 99 13 26 0 1238303168 124372944 3220928
04:02:01 63602 8192000000 10050686384 10517819392 1108220768 11.916 99 7 14 0 1156271872 46713472 1337632
04:04:04 24150 8192000000 10062420416 10511962112 1111796704 11.949 99 25 47 1 1151760448 35072496 4891248
04:05:29 37484 8192000000 10099229488 10541940736 1103802784 11.874 98 34 66 0 1180731920 69908720 7020416
04:07:15 46214 8192000000 10089927856 10528985088 1115634800 11.986 99 21 40 0 1208098208 87699216 4764192
04:09:02 69528 8192000000 10136531456 10620051456 1108525536 11.918 98 17 34 0 1228098448 115640240 3932672
04:12:20 6558 8192000000 10073105184 10493890560 1127034336 12.093 99 56 107 2 1160775152 22896016 10844800
04:13:30 15074 8192000000 10119064880 10573160448 1124124464 12.066 100 40 78 1 1219306688 87214560 7967664
04:14:48 16256 8192000000 10089388704 10508267520 1121843856 12.044 99 70 135 1 1175826304 39524304 14458144
04:16:06 115326 8192000000 10085020912 10539659264 1119601904 12.023 99 32 63 1 1179240624 53407696 6231024
04:19:03 53026 8192000000 10121876000 10581786624 1132214320 12.142 99 34 61 0 1195666800 57190448 6262032
04:21:16 45071 8192000000 10076996496 10541289472 1114778144 11.978 98 17 34 0 1165205280 46751168 3675968
04:23:01 44925 8192000000 10046718464 10532229120 1106115232 11.896 98 25 48 0 1166138656 54827072 5196352
04:24:46 47050 8192000000 10071955312 10539147264 1124081552 12.066 100 14 28 0 1170343040 43423648 2837840
04:27:11 67404 8192000000 10077055648 10511204352 1064590384 11.500 94 34 66 1 1119539808 47122912 7826512
04:29:24 30269 8192000000 10113203648 10564878336 1135679392 12.175 100 17 34 0 1213973568 74857456 3436720
04:31:02 40800 8192000000 10160054160 10600112128 1126427792 12.088 98 34 68 1 1230289888 96605744 7256352
04:32:44 66117 8192000000 10106115200 10613075968 1133242528 12.152 99 16 31 1 1205789520 69226624 3320368
04:34:50 10528 8192000000 10106042512 10541117440 1142072384 12.235 100 51 99 1 1197040256 45079136 9888736
04:36:03 22284 8192000000 10187684608 10668167168 1139826160 12.214 100 50 100 0 1268614560 118288976 10499424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 22-02-2023 04:38:46 to 22-02-2023 04:39:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 58 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-02-2023 04:39:04 to 22-02-2023 04:39:06.

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          236954
MON$OLDEST_ACTIVE               236955
MON$OLDEST_SNAPSHOT             236955
MON$NEXT_TRANSACTION            236955
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                       2086560
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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.02.23 04:39:07. 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-22 04:39:10 No severe PSQL-related problems occured
22.02.23 04:39:11. End of report.