Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2022 10:39
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 15948380 kB
MemAvailable 30935744 kB
Buffers 0 kB
Cached 15795560 kB
SwapCached 2512 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-08-15 10:37:50
DTS_END                         2022-08-15 10:37:21.7430
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/33648
TRN_ID                          216671
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: 9001.72 21305 1080206
customer order: creation 167.91 499 20149
customer order: refuse 13.34 369 1601
order to supplier: creation 44.09 1416 5291
order to supplier: removal 3.47 169064 416
invoice (draft): creation 44.51 2968 5341
invoice (draft): removal 49.74 1066 5969
invoice accept: apply 41.01 26843 4921
invoice accept: cancel 45.12 1251 5414
customer reserve: creation 3851.43 254 462171
customer reserve: removal 4453.77 105 534452
realization accept: apply 78.64 279 9437
realization accept: cancel 85.05 172 10206
payment from customer: creation 56.37 175 6764
payment from customer: removal 5.53 135 664
payment to supplier: creation 45.88 203 5505
payment to supplier: removal 3.46 107 415
service: total inventory turnovers 4.08 3349 490
service: total monetary turnovers 7.33 303 880
service: aggregate perf. data 0.97 11995 116
service: refresh index statistics 0.03 205552 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2022 10:39:59 to 15-08-2022 10:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6279
TEST_TIME 2 5740
TEST_TIME 3 6164
TEST_TIME 4 7023
TEST_TIME 5 6445
TEST_TIME 6 6200
TEST_TIME 7 6236
TEST_TIME 8 6123
TEST_TIME 9 6076
TEST_TIME 10 6235
TEST_TIME 11 6285
TEST_TIME 12 6295
TEST_TIME 13 6233
TEST_TIME 14 6213
TEST_TIME 15 6428
TEST_TIME 16 6635
TEST_TIME 17 6627
TEST_TIME 18 7124
TEST_TIME 19 7197
TEST_TIME 20 7122
TEST_TIME 21 7579
TEST_TIME 22 8015
TEST_TIME 23 8488
TEST_TIME 24 8665
TEST_TIME 25 8798
TEST_TIME 26 9504
TEST_TIME 27 10441
TEST_TIME 28 11249
TEST_TIME 29 11083
TEST_TIME 30 11017
TEST_TIME 31 10830
TEST_TIME 32 10613
TEST_TIME 33 10587
TEST_TIME 34 10416
TEST_TIME 35 10255
TEST_TIME 36 10307
TEST_TIME 37 10219
TEST_TIME 38 10152
TEST_TIME 39 10159
TEST_TIME 40 10073
TEST_TIME 41 9895
TEST_TIME 42 9744
TEST_TIME 43 9631
TEST_TIME 44 10044
TEST_TIME 45 10043
TEST_TIME 46 9957
TEST_TIME 47 9879
TEST_TIME 48 10011
TEST_TIME 49 10024
TEST_TIME 50 10009
TEST_TIME 51 9936
TEST_TIME 52 9933
TEST_TIME 53 9891
TEST_TIME 54 10065
TEST_TIME 55 9968
TEST_TIME 56 9797
TEST_TIME 57 9723
TEST_TIME 58 9728
TEST_TIME 59 9642
TEST_TIME 60 9595
TEST_TIME 61 9581
TEST_TIME 62 9562
TEST_TIME 63 9454
TEST_TIME 64 9461
TEST_TIME 65 9442
TEST_TIME 66 9387
TEST_TIME 67 9368
TEST_TIME 68 9371
TEST_TIME 69 9347
TEST_TIME 70 9369
TEST_TIME 71 9443
TEST_TIME 72 9406
TEST_TIME 73 9668
TEST_TIME 74 9718
TEST_TIME 75 9671
TEST_TIME 76 9567
TEST_TIME 77 9521
TEST_TIME 78 9449
TEST_TIME 79 9468
TEST_TIME 80 9430
TEST_TIME 81 9365
TEST_TIME 82 9363
TEST_TIME 83 9344
TEST_TIME 84 9314
TEST_TIME 85 9304
TEST_TIME 86 9277
TEST_TIME 87 9293
TEST_TIME 88 9278
TEST_TIME 89 9327
TEST_TIME 90 9350
TEST_TIME 91 9371
TEST_TIME 92 9325
TEST_TIME 93 9332
TEST_TIME 94 9402
TEST_TIME 95 9413
TEST_TIME 96 9405
TEST_TIME 97 9371
TEST_TIME 98 9294
TEST_TIME 99 9273
TEST_TIME 100 9239
TEST_TIME 101 9189
TEST_TIME 102 9159
TEST_TIME 103 9121
TEST_TIME 104 9122
TEST_TIME 105 9094
TEST_TIME 106 9054
TEST_TIME 107 9049
TEST_TIME 108 9056
TEST_TIME 109 9068
TEST_TIME 110 9052
TEST_TIME 111 9088
TEST_TIME 112 9056
TEST_TIME 113 9055
TEST_TIME 114 9131
TEST_TIME 115 9110
TEST_TIME 116 9143
TEST_TIME 117 9163
TEST_TIME 118 9128
TEST_TIME 119 9061
TEST_TIME 120 9020

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2022 10:40:03 to 15-08-2022 10:40:06.

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 1505195 1505195 0 0.00 0 22449 13 0 0
doc_list_biud 1505178 1505178 0 0.00 0 42044 43 0 0
sp_add_invoice_to_stock 5015 4921 94 1.87 38 277728 26844 0 94
sp_cancel_adding_invoice 5414 5414 0 0.00 6 92213 1252 0 0
sp_cancel_client_order 1601 1601 0 0.00 6 9085 369 0 0
sp_cancel_customer_reserve 534452 534452 0 0.00 1 42045 105 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 8628 135 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 2891 107 0 0
sp_cancel_supplier_invoice 5979 5969 10 0.16 2 16241 1067 0 10
sp_cancel_supplier_order 416 416 0 0.00 9 1492367 169064 0 0
sp_cancel_write_off 10206 10206 0 0.00 2 11532 173 0 0
sp_client_order 20149 20149 0 0.00 5 45992 499 0 0
sp_customer_reserve 462171 462171 0 0.00 0 136495 255 0 0
sp_fill_shopping_cart 30766 30766 0 0.00 1 9895 75 0 0
sp_fill_shopping_cart_clo_res 462129 462129 0 0.00 0 14430 22 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 26384 341 0 0
sp_kill_cost_storno 16699 16699 0 0.00 0 15762 72 0 0
sp_kill_qstorno_ret_qs2qd 1085495 1085495 0 0.00 0 42042 47 0 0
sp_kill_qty_storno 572374 572374 0 0.00 0 42044 105 0 0
sp_lock_selected_doc 19794 19794 0 0.00 0 10317 9 0 0
sp_make_cost_storno 26585 26585 0 0.00 0 21920 86 0 0
sp_multiply_rows_for_pdistr 26585 26585 0 0.00 0 8637 35 0 0
sp_multiply_rows_for_qdistr 20144 20144 0 0.00 0 9840 221 0 0
sp_pay_from_customer 6764 6764 0 0.00 5 22496 175 0 0
sp_pay_to_supplier 5505 5505 0 0.00 6 22324 203 0 0
sp_payment_common 12269 12269 0 0.00 2 18662 45 0 0
sp_qd_handle_on_cancel_clo 1601 1601 0 0.00 0 9077 289 0 0
sp_qd_handle_on_invoice_upd_sts 10293 10293 0 0.00 0 19444 466 0 0
sp_qd_handle_on_reserve_upd_sts 9437 9437 0 0.00 0 19848 64 0 0
sp_reserve_write_off 9437 9437 0 0.00 9 22488 279 0 0
sp_supplier_invoice 5341 5341 0 0.00 8 89898 2969 0 0
sp_supplier_order 5291 5291 0 0.00 7 65081 1417 0 0
srv_aggregate_perf_data 117 116 1 0.85 2519 114070 11995 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 953 139308 37253 0 0
srv_find_qd_qs_mism 1032139 1032139 0 0.00 0 13335 11 0 0
srv_make_invnt_saldo 490 490 0 0.00 80 59138 3350 0 0
srv_make_money_saldo 880 880 0 0.00 1 6674 303 0 0
srv_recalc_idx_stat 4 4 0 0.00 91517 372217 205552 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4921 4921 0 0.00 0 19381 777 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 2 8803 439 0 0
t$perf-norm:sp_cancel_client_order 1601 1601 0 0.00 0 4561 27 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 7328 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 743 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 2416 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 924 23 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 1 69571 5418 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 12991 36 0 0
t$perf-norm:sp_client_order 20149 20149 0 0.00 0 13796 34 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 686 13 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 13836 24 0 0
t$perf-norm:sp_pay_to_supplier 5505 5505 0 0.00 0 13207 23 0 0
t$perf-norm:sp_reserve_write_off 9437 9437 0 0.00 0 14689 29 0 0
t$perf-norm:sp_supplier_invoice 5341 5341 0 0.00 0 2939 26 0 0
t$perf-norm:sp_supplier_order 5291 5291 0 0.00 0 10151 28 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 12300 113 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1558 31 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 4263 26 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 13817 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 105 43 0 0
v_add_invoice_to_stock 9946 9758 188 1.89 0 8736 25 0 188
v_all_customers 36632 36632 0 0.00 0 160 3 0 0
v_all_suppliers 29380 29380 0 0.00 0 140 4 0 0
v_all_wares 40288 40288 0 0.00 0 160 3 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 579 14 0 0
v_cancel_client_order 3202 3202 0 0.00 0 4693 16 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 6082 15 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 1286 11 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2723 16 0 0
v_cancel_supplier_invoice 770 750 20 2.59 0 1661 21 0 20
v_cancel_supplier_order 832 832 0 0.00 0 3218 16 0 0
v_cancel_write_off 1948 1948 0 0.00 0 3308 28 0 0
v_max_id_clo_ord 5288 5288 0 0.00 0 2159 5 0 0
v_max_id_clo_res 947 947 0 0.00 0 2876 19 0 0
v_max_id_ord_sup 5334 5334 0 0.00 0 6150 7 0 0
v_max_non_paid_invoice 1437 1437 0 0.00 0 229 7 0 0
v_max_non_paid_realizn 3477 3477 0 0.00 0 1814 7 0 0
v_min_id_clo_ord 5288 5288 0 0.00 0 1965 7 0 0
v_min_id_clo_res 947 947 0 0.00 0 1959 9 0 0
v_min_id_ord_sup 5334 5334 0 0.00 0 8195 16 0 0
v_min_non_paid_invoice 5505 5505 0 0.00 0 7216 15 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 10637 22 0 0
v_our_firm 10230 10230 0 0.00 0 69 4 0 0
v_reserve_write_off 18874 18874 0 0.00 0 4834 11 0 0
x_lock_depdocs_on_canc_invoice 11008 11008 0 0.00 0 20183 124 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 13284 376 0 0
x_make_qstorno_1000_1200 5291 5291 0 0.00 2 64854 1277 0 0
x_make_qstorno_1200_2000 5341 5341 0 0.00 4 88357 2750 0 0
x_make_qstorno_2100_3300 462171 462171 0 0.00 0 136490 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2022 10:40:07 to 15-08-2022 10:40:11.

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:24 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:26 20940 8192000000 9718693328 10014199808 796790048 8.864 100 92 184 0 827533312 8040560 22702704
08:09:55 11362 8192000000 9755234080 10062041088 833545280 9.235 100 89 178 0 866733952 11622096 21566576
08:11:08 20033 8192000000 9795676320 10111578112 866469184 9.565 100 83 166 0 902412048 16487904 19454960
08:12:32 6983 8192000000 9838391952 10172768256 908523168 9.983 100 71 142 0 952935104 28821424 15590512
08:13:47 3673 8192000000 9915572992 10257293312 962189824 10.510 100 86 170 0 1037231728 57403104 17638800
08:14:56 1902 8192000000 9972567552 10324041728 994465712 10.825 100 89 171 1 1086363744 74747168 17150864
08:16:00 1617 8192000000 9976606032 10338000896 1018259728 11.055 100 91 171 1 1101926304 67625696 16040880
08:17:03 1055 8192000000 10016265584 10387877888 1044945472 11.312 100 91 169 2 1135208176 73201456 17061248
08:18:05 854 8192000000 10026058896 10401046528 1056057744 11.419 100 96 188 0 1148469008 73775616 18635648
08:19:15 3819 8192000000 10024539120 10401095680 1071856544 11.570 100 83 159 3 1145997984 59554272 14587168
08:20:20 10766 8192000000 10027081600 10410414080 1077696800 11.626 100 74 138 1 1144084800 53161312 13226688
08:21:33 1012 8192000000 10055333312 10441383936 1085519760 11.700 100 91 170 2 1178126624 73343120 19263744
08:22:36 2749 8192000000 10060012320 10450231296 1090087712 11.743 100 82 151 0 1179299296 72972240 16239344
08:23:39 1165 8192000000 10051162208 10440675328 1097388016 11.813 100 85 161 0 1177869776 63834096 16647664
08:24:41 994 8192000000 10087253008 10478833664 1097835328 11.817 100 94 182 1 1208494848 91345440 19314080
08:25:43 1020 8192000000 10054506288 10446917632 1099876608 11.836 100 86 164 1 1179195072 62285952 17032512
08:26:46 2166 8192000000 10062369344 10449174528 1093404304 11.775 100 85 165 1 1182597024 73006288 16186432
08:27:49 1136 8192000000 10032997216 10424012800 1080439776 11.652 99 84 157 0 1153287984 56409264 16438944
08:28:51 870 8192000000 10049062800 10440085504 1084542544 11.691 100 89 173 5 1171417536 68671072 18203920
08:29:53 1362 8192000000 10028949312 10407915520 1064394240 11.499 100 96 190 2 1152150752 68481488 19275024
08:30:55 1185 8192000000 10041225616 10420723712 1069228480 11.545 100 91 175 1 1162185648 75838272 17118896
08:31:57 1881 8192000000 10023173056 10406662144 1066808976 11.522 100 81 158 1 1152080608 68906672 16364960
08:33:00 2356 8192000000 9994861696 10380308480 1053673344 11.396 100 81 158 1 1117777360 47893648 16210368
08:34:03 3337 8192000000 9997979168 10373586944 1036861280 11.234 99 79 150 2 1124101232 72303840 14936112
08:35:07 1150 8192000000 10007395280 10387881984 1047713616 11.339 100 91 171 0 1131194976 65047424 18433936
08:36:09 1046 8192000000 10008377984 10394304512 1050612752 11.367 100 91 173 3 1134887616 64580816 19694048
08:37:11 1194 8192000000 9987540464 10359771136 1041120064 11.275 100 94 178 1 1102566176 44361776 17084336
08:38:22 3378 8192000000 10013733824 10388606976 1058505664 11.442 100 85 142 6 1128836832 57861552 12469616
08:39:30 2925 8192000000 10024704816 10409771008 1079570128 11.643 100 69 133 1 1146049584 52557520 13921936
08:40:38 1740 8192000000 10075914000 10459267072 1091863024 11.760 100 82 161 0 1197499344 88654208 16982112
08:41:44 4123 8192000000 10078967056 10470645760 1102872896 11.865 100 72 141 2 1207528192 88789744 15865552
08:42:51 3665 8192000000 10127321264 10528505856 1120415760 12.031 100 71 136 0 1247539968 111822320 15301888
08:43:59 2536 8192000000 10180389616 10581422080 1141880400 12.233 100 72 136 0 1293373616 136840032 14653184
08:45:07 1266 8192000000 10212512448 10609360896 1163733472 12.438 100 77 149 0 1323923760 143982400 16207888
08:46:11 1677 8192000000 10271624976 10669088768 1174614912 12.540 100 78 142 2 1385038720 194302224 16121584
08:47:18 1170 8192000000 10331429024 10727940096 1182535952 12.614 100 97 191 0 1436146128 232382528 21227648
08:48:23 1299 8192000000 10374806368 10770386944 1184787440 12.635 100 91 172 0 1478327360 274315664 19224256
08:49:28 1234 8192000000 10407344864 10806935552 1192379984 12.706 100 94 182 0 1522245968 307900704 21965280
08:50:34 1325 8192000000 10441414976 10844811264 1198525344 12.763 100 92 177 1 1561244928 340066160 22653424
08:51:37 1794 8192000000 10488415344 10904920064 1195152528 12.731 100 94 176 2 1594121696 378443104 20526064
08:52:42 1123 8192000000 10550120304 10955214848 1205439824 12.827 100 97 191 1 1657671616 428981568 23250224
08:53:47 947 8192000000 10580336496 10992062464 1215364784 12.919 100 91 176 0 1692791168 455589936 21836448
08:54:51 1680 8192000000 10587067728 11037130752 1209789040 12.867 100 96 192 1 1690378832 456663472 23926320
08:55:55 3023 8192000000 10600888368 11036078080 1205060544 12.823 100 68 135 0 1712532352 490644704 16827104
08:57:05 1399 8192000000 10632477664 11056267264 1197435712 12.753 100 78 149 0 1737904288 521407168 19061408
08:58:08 1105 8192000000 10658797824 11104120832 1200357280 12.780 100 95 182 0 1778983856 554329872 24296704
08:59:11 3360 8192000000 10581463072 11054075904 1182674096 12.615 100 60 118 2 1687953184 488710352 16568736
09:00:22 11114 8192000000 10549687680 11016732672 1183052400 12.619 100 50 100 0 1661080928 464903488 13125040
09:01:44 32133 8192000000 10513274864 10956648448 1163968672 12.440 99 36 72 1 1619514688 446056544 9489472
09:03:21 25099 8192000000 10424120608 10925309952 1133731680 12.157 99 32 64 0 1515389840 373512208 8145952
09:05:02 40081 8192000000 10089719952 10655178752 1125061984 12.075 99 13 26 0 1307973568 179587200 3324384
09:07:02 14453 8192000000 10061755360 10517426176 1123656752 12.062 99 27 52 0 1162088832 32186384 6245696
09:08:19 139307 8192000000 10074674416 10546536448 1100355104 11.841 97 22 44 0 1139562656 34596640 4610912
09:11:41 52629 8192000000 10105733152 10534670336 1126193296 12.085 99 37 69 0 1179998880 46804624 7000960
09:13:35 38950 8192000000 10103230800 10546720768 1119714896 12.024 98 25 46 0 1191977152 66990064 5272192
09:15:18 30717 8192000000 10137324320 10592514048 1136909056 12.186 99 50 90 1 1233734896 86770544 10055296
09:16:49 120875 8192000000 10143232304 10588368896 1112058928 11.952 97 14 26 1 1228739840 113496000 3184912
09:20:09 60113 8192000000 10186282640 10665623552 1145505344 12.267 100 18 34 1 1275235200 124664096 5065760
09:22:21 49450 8192000000 10100054656 10552844288 1131423616 12.135 99 30 57 0 1162824672 25558752 5842304
09:24:13 17866 8192000000 10124423232 10563772416 1135316944 12.171 99 37 74 1 1202257600 59480160 7460496
09:25:31 37178 8192000000 10089875792 10541056000 1130385184 12.125 99 34 61 3 1177726784 41405600 5936000
09:27:19 39479 8192000000 10076090960 10519560192 1114870928 11.979 98 15 28 0 1155887296 37737856 3278512
09:28:59 51614 8192000000 10164061216 10624311296 1136570272 12.183 100 36 72 0 1236741776 92881248 7290256
09:31:00 53812 8192000000 10064570128 10510147584 1104118016 11.877 98 24 47 1 1164720192 55805168 4797008
09:32:55 84653 8192000000 10098251968 10535534592 1125607840 12.080 100 58 111 1 1211566560 74835136 11123584
09:35:21 33524 8192000000 10074979200 10544934912 1113176768 11.962 99 20 40 0 1163452016 46033600 4241648
09:37:34 47024 8192000000 10079312416 10574925824 1132629744 12.146 100 12 23 0 1163815952 29224256 1961952
09:39:22 37432 8192000000 10092740272 10553683968 1107494240 11.909 97 27 53 0 1153646544 41246224 4906080
09:41:00 23582 8192000000 10145485232 10593292288 1116256080 11.992 97 36 72 0 1206153408 82306784 7590544
09:42:29 21927 8192000000 10170419600 10608803840 1149216016 12.302 100 33 62 0 1250057344 92994608 7846720
09:43:51 33532 8192000000 10215354816 10658701312 1142576720 12.240 98 39 78 0 1280697536 129242576 8878240
09:45:26 17225 8192000000 10262226256 10697617408 1150088432 12.310 100 48 94 1 1355796976 195342064 10366480
09:46:46 31811 8192000000 10220587424 10681155584 1134681040 12.165 99 39 78 0 1320098112 176863808 8553264
09:48:18 34570 8192000000 10209948448 10690727936 1147724400 12.288 100 29 56 0 1304572496 149919856 6928240
09:49:53 46436 8192000000 10167857920 10649350144 1135861616 12.177 99 32 60 0 1273081616 130379088 6840912
09:51:40 52088 8192000000 10141686144 10591338496 1125694416 12.081 98 41 82 0 1203094848 68925648 8474784
09:53:46 59921 8192000000 10125689744 10579812352 1137861792 12.195 100 39 78 0 1208831264 62693392 8276080
09:56:04 68055 8192000000 10124285440 10609774592 1140483744 12.220 100 16 32 0 1232112672 88189456 3439472
09:59:12 67185 8192000000 10132288688 10599604224 1148229056 12.293 100 14 28 0 1225617632 74373408 3015168
10:01:55 56116 8192000000 10096279888 10546487296 1130257744 12.124 98 18 35 0 1184020560 50145200 3617616
10:03:55 57425 8192000000 10102087328 10552258560 1116382400 11.993 97 16 32 0 1186147392 66296144 3468848
10:05:52 60998 8192000000 10099127088 10583769088 1100246640 11.840 96 28 56 0 1190246896 84190336 5809920
10:08:02 52653 8192000000 10089092064 10545426432 1103988720 11.875 97 33 64 1 1174212736 63478128 6745888
10:10:06 52449 8192000000 10150985120 10594095104 1125430672 12.078 98 37 74 0 1223377824 90573696 7373456
10:12:03 111364 8192000000 10107473648 10547736576 1100708400 11.844 96 31 62 0 1170690240 64091440 5890400
10:15:01 86348 8192000000 10117095776 10553495552 1124329680 12.068 99 50 95 0 1209467616 76048800 9089136
10:17:39 45336 8192000000 10051215376 10523848704 1112396208 11.955 100 10 20 0 1158694928 44290832 2007888
10:19:25 70382 8192000000 10063885120 10540617728 1087998624 11.724 97 8 16 0 1125349792 35808224 1542944
10:21:38 102224 8192000000 10097912736 10543894528 1093251280 11.774 97 13 26 0 1128622560 33709168 1662112
10:24:21 51651 8192000000 10133700464 10580570112 1135621584 12.174 100 19 36 0 1225620592 85762320 4236688
10:26:12 28887 8192000000 10128408000 10591838208 1121113584 12.038 99 32 66 0 1219075936 91090784 6871568
10:27:47 42470 8192000000 10151849744 10640744448 1149297328 12.303 100 37 74 0 1244084464 86529376 8257760
10:29:42 75754 8192000000 10162445408 10610352128 1144929088 12.262 99 26 52 0 1240979632 90221056 5829488
10:32:13 39656 8192000000 10131382192 10568208384 1125347344 12.077 98 30 57 2 1199678912 68423328 5908240
10:33:53 48793 8192000000 10117611712 10615279616 1136625776 12.184 100 23 46 0 1201308928 60452416 4230736
10:35:42 73130 8192000000 10081916656 10512670720 1105756832 11.892 97 35 68 2 1144195152 31590576 6847744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-08-2022 10:40:13 to 15-08-2022 10:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 94 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2022 10:40:30 to 15-08-2022 10:40:32.

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          214891
MON$OLDEST_ACTIVE               218986
MON$OLDEST_SNAPSHOT             218986
MON$NEXT_TRANSACTION            218986
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                       2032736
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:303: 
:304: mscs-test-161.budzdorov.ru    Mon Aug 15 10:37:55 2022
:305:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:306: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

15.08.22 10:40:33. 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-08-15 10:40:35 No severe PSQL-related problems occured
15.08.22 10:40:37. End of report.