Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.2022 07:48
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 15298148 kB
MemAvailable 30564460 kB
Buffers 0 kB
Cached 16437740 kB
SwapCached 2264 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 2022-10-08 07:47:13
DTS_END                         2022-10-08 07:47:13.1310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_FROM_CUSTOMER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42524
TRN_ID                          220610
ATT_ID                          619
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: 9003.92 21898 1080470
customer order: creation 171.47 455 20576
customer order: refuse 14.28 474 1713
order to supplier: creation 45.30 1418 5436
order to supplier: removal 3.54 167849 425
invoice (draft): creation 45.52 3112 5462
invoice (draft): removal 50.63 1104 6076
invoice accept: apply 40.50 26067 4860
invoice accept: cancel 45.38 1314 5446
customer reserve: creation 3832.94 243 459953
customer reserve: removal 4465.47 104 535856
realization accept: apply 81.83 268 9819
realization accept: cancel 77.24 193 9269
payment from customer: creation 59.29 168 7115
payment from customer: removal 6.03 117 723
payment to supplier: creation 48.58 181 5830
payment to supplier: removal 3.46 140 415
service: total inventory turnovers 3.96 4563 475
service: total monetary turnovers 7.47 415 896
service: aggregate perf. data 1.00 13027 120
service: refresh index statistics 0.04 216738 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-10-2022 07:48:49 to 08-10-2022 07:48:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7031
TEST_TIME 2 6336
TEST_TIME 3 5706
TEST_TIME 4 5964
TEST_TIME 5 5876
TEST_TIME 6 5677
TEST_TIME 7 5461
TEST_TIME 8 5711
TEST_TIME 9 5937
TEST_TIME 10 6094
TEST_TIME 11 6120
TEST_TIME 12 6261
TEST_TIME 13 6347
TEST_TIME 14 6320
TEST_TIME 15 6458
TEST_TIME 16 6590
TEST_TIME 17 6669
TEST_TIME 18 6720
TEST_TIME 19 6676
TEST_TIME 20 6834
TEST_TIME 21 7064
TEST_TIME 22 7347
TEST_TIME 23 7827
TEST_TIME 24 8047
TEST_TIME 25 8404
TEST_TIME 26 8817
TEST_TIME 27 9212
TEST_TIME 28 9669
TEST_TIME 29 9507
TEST_TIME 30 9644
TEST_TIME 31 10039
TEST_TIME 32 9898
TEST_TIME 33 9989
TEST_TIME 34 10013
TEST_TIME 35 10179
TEST_TIME 36 10239
TEST_TIME 37 10498
TEST_TIME 38 10335
TEST_TIME 39 10276
TEST_TIME 40 10227
TEST_TIME 41 10230
TEST_TIME 42 10086
TEST_TIME 43 9859
TEST_TIME 44 9929
TEST_TIME 45 9860
TEST_TIME 46 9708
TEST_TIME 47 9677
TEST_TIME 48 9695
TEST_TIME 49 9636
TEST_TIME 50 9581
TEST_TIME 51 9689
TEST_TIME 52 9667
TEST_TIME 53 9570
TEST_TIME 54 9504
TEST_TIME 55 9500
TEST_TIME 56 9412
TEST_TIME 57 9414
TEST_TIME 58 9330
TEST_TIME 59 9260
TEST_TIME 60 9255
TEST_TIME 61 9232
TEST_TIME 62 9230
TEST_TIME 63 9200
TEST_TIME 64 9266
TEST_TIME 65 9237
TEST_TIME 66 9213
TEST_TIME 67 9186
TEST_TIME 68 9158
TEST_TIME 69 9199
TEST_TIME 70 9229
TEST_TIME 71 9275
TEST_TIME 72 9400
TEST_TIME 73 9470
TEST_TIME 74 9552
TEST_TIME 75 9529
TEST_TIME 76 9530
TEST_TIME 77 9429
TEST_TIME 78 9351
TEST_TIME 79 9379
TEST_TIME 80 9350
TEST_TIME 81 9346
TEST_TIME 82 9287
TEST_TIME 83 9229
TEST_TIME 84 9167
TEST_TIME 85 9104
TEST_TIME 86 9072
TEST_TIME 87 9030
TEST_TIME 88 8974
TEST_TIME 89 8951
TEST_TIME 90 8956
TEST_TIME 91 8921
TEST_TIME 92 9042
TEST_TIME 93 9032
TEST_TIME 94 9077
TEST_TIME 95 9124
TEST_TIME 96 9090
TEST_TIME 97 9095
TEST_TIME 98 9175
TEST_TIME 99 9190
TEST_TIME 100 9219
TEST_TIME 101 9187
TEST_TIME 102 9160
TEST_TIME 103 9143
TEST_TIME 104 9085
TEST_TIME 105 9020
TEST_TIME 106 8993
TEST_TIME 107 8950
TEST_TIME 108 8908
TEST_TIME 109 8912
TEST_TIME 110 8890
TEST_TIME 111 8896
TEST_TIME 112 8910
TEST_TIME 113 8957
TEST_TIME 114 8941
TEST_TIME 115 8971
TEST_TIME 116 9028
TEST_TIME 117 9057
TEST_TIME 118 9119
TEST_TIME 119 9101
TEST_TIME 120 9030

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-10-2022 07:48:53 to 08-10-2022 07:48:56.

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 1503219 1503219 0 0.00 0 32840 14 0 0
doc_list_biud 1503201 1503201 0 0.00 0 39320 44 0 0
sp_add_invoice_to_stock 4951 4860 91 1.83 28 223062 26067 0 91
sp_cancel_adding_invoice 5446 5446 0 0.00 6 70042 1314 0 0
sp_cancel_client_order 1713 1713 0 0.00 6 9480 475 0 0
sp_cancel_customer_reserve 535856 535856 0 0.00 1 28695 105 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 4 1781 118 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 8619 141 0 0
sp_cancel_supplier_invoice 6086 6076 10 0.16 3 17369 1104 0 10
sp_cancel_supplier_order 425 425 0 0.00 6 1306328 167850 0 0
sp_cancel_write_off 9269 9269 0 0.00 1 9892 193 0 0
sp_client_order 20576 20576 0 0.00 6 34236 455 0 0
sp_customer_reserve 459953 459953 0 0.00 1 92463 243 0 0
sp_fill_shopping_cart 31463 31463 0 0.00 1 14795 85 0 0
sp_fill_shopping_cart_clo_res 459919 459919 0 0.00 0 13867 18 0 0
sp_get_clo_for_invoice 4818 4818 0 0.00 0 21032 276 0 0
sp_kill_cost_storno 15853 15853 0 0.00 0 10459 81 0 0
sp_kill_qstorno_ret_qs2qd 1087482 1087482 0 0.00 0 28639 47 0 0
sp_kill_qty_storno 573421 573421 0 0.00 0 39316 107 0 0
sp_lock_selected_doc 20476 20476 0 0.00 0 8605 8 0 0
sp_make_cost_storno 27580 27580 0 0.00 0 9092 76 0 0
sp_multiply_rows_for_pdistr 27580 27580 0 0.00 0 7251 39 0 0
sp_multiply_rows_for_qdistr 20576 20576 0 0.00 0 15318 188 0 0
sp_pay_from_customer 7115 7115 0 0.00 5 10677 169 0 0
sp_pay_to_supplier 5830 5830 0 0.00 6 8574 182 0 0
sp_payment_common 12944 12944 0 0.00 2 5739 43 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 9447 397 0 0
sp_qd_handle_on_invoice_upd_sts 10263 10263 0 0.00 0 39313 562 0 0
sp_qd_handle_on_reserve_upd_sts 9819 9819 0 0.00 0 19059 59 0 0
sp_reserve_write_off 9819 9819 0 0.00 8 19159 269 0 0
sp_supplier_invoice 5462 5462 0 0.00 11 78164 3113 0 0
sp_supplier_order 5436 5436 0 0.00 9 41276 1418 0 0
srv_aggregate_perf_data 121 120 1 0.82 2313 178016 13027 0 1
srv_fill_mon_cache_memory 75 75 0 0.00 931 119396 31105 0 0
srv_find_qd_qs_mism 1031291 1031291 0 0.00 0 32839 11 0 0
srv_make_invnt_saldo 475 475 0 0.00 50 103732 4563 0 0
srv_make_money_saldo 896 896 0 0.00 0 13902 415 0 0
srv_recalc_idx_stat 5 5 0 0.00 94264 411807 216739 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 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 4868 4868 0 0.00 0 20754 939 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 2 5770 452 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 2263 28 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2117 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 498 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 424 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1265 45 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 98550 7082 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 2272 21 0 0
t$perf-norm:sp_client_order 20576 20576 0 0.00 0 9335 38 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 1479 15 0 0
t$perf-norm:sp_pay_from_customer 7115 7115 0 0.00 0 6767 19 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 10394 24 0 0
t$perf-norm:sp_reserve_write_off 9819 9819 0 0.00 0 10702 22 0 0
t$perf-norm:sp_supplier_invoice 5462 5462 0 0.00 0 8036 32 0 0
t$perf-norm:sp_supplier_order 5436 5436 0 0.00 0 15507 35 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 941 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 121 4 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 3008 20 0 0
t$perf-norm:srv_make_money_saldo 896 896 0 0.00 0 579 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 19 150 76 0 0
v_add_invoice_to_stock 9810 9628 182 1.85 0 4371 25 0 182
v_all_customers 37966 37966 0 0.00 0 111 4 0 0
v_all_suppliers 30490 30490 0 0.00 0 99 4 0 0
v_all_wares 41150 41150 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 679 15 0 0
v_cancel_client_order 3426 3426 0 0.00 0 2951 12 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 1649 14 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 1 1646 15 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3199 15 0 0
v_cancel_supplier_invoice 876 856 20 2.28 0 3088 32 0 20
v_cancel_supplier_order 850 850 0 0.00 0 329 10 0 0
v_cancel_write_off 2138 2138 0 0.00 0 1846 24 0 0
v_max_id_clo_ord 5435 5435 0 0.00 0 1516 5 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 7925 21 0 0
v_max_id_ord_sup 5451 5451 0 0.00 0 7110 9 0 0
v_max_non_paid_invoice 1470 1470 0 0.00 0 1115 10 0 0
v_max_non_paid_realizn 3552 3552 0 0.00 0 2669 8 0 0
v_min_id_clo_ord 5435 5435 0 0.00 0 13178 12 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 1667 12 0 0
v_min_id_ord_sup 5451 5451 0 0.00 0 8576 10 0 0
v_min_non_paid_invoice 5829 5829 0 0.00 0 4334 14 0 0
v_min_non_paid_realizn 7115 7115 0 0.00 0 5314 16 0 0
v_our_firm 10310 10310 0 0.00 0 62 4 0 0
v_reserve_write_off 19635 19635 0 0.00 0 3199 10 0 0
x_lock_depdocs_on_canc_invoice 11094 11094 0 0.00 0 19155 118 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 4764 357 0 0
x_make_qstorno_1000_1200 5436 5436 0 0.00 2 41266 1249 0 0
x_make_qstorno_1200_2000 5462 5462 0 0.00 5 78149 2870 0 0
x_make_qstorno_2100_3300 459952 459952 0 0.00 0 92460 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2022 07:48:58 to 08-10-2022 07:49:02.

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
05:17:16 36 8192000000 8807461104 8821923840 8326480 0.101 5 1 2 0 8431200 68944 35776
05:18:17 26299 8192000000 9714066496 10013769728 792714352 8.822 100 87 175 0 822609840 7784976 22110512
05:19:55 21457 8192000000 9768194720 10076655616 839931552 9.299 100 92 185 0 874489056 12495136 22062368
05:21:25 14104 8192000000 9805605792 10123726848 868447408 9.585 100 86 170 0 908499536 20181056 19871072
05:22:49 7920 8192000000 9859497984 10194206720 912835376 10.025 100 71 142 1 967780128 39396864 15547888
05:24:01 3542 8192000000 9917155216 10262904832 960557616 10.494 100 93 181 0 1030148272 50458016 19132640
05:25:07 2468 8192000000 9963329648 10316300288 997644704 10.856 100 85 160 0 1078463488 65157168 15661616
05:26:12 1886 8192000000 10006678880 10366787584 1021613136 11.088 100 91 180 1 1122968096 82863872 18491088
05:27:16 1566 8192000000 10014472704 10381656064 1035200832 11.219 100 91 169 4 1128763296 76992432 16570032
05:28:19 1576 8192000000 10010344128 10383347712 1056362864 11.422 100 93 168 2 1135702464 62961824 16377776
05:29:22 1478 8192000000 10039686624 10422464512 1064748416 11.502 100 93 176 2 1157445184 74983568 17713200
05:30:26 1081 8192000000 10056165344 10436775936 1073271968 11.583 100 92 183 1 1176763792 85481440 18010384
05:31:27 11140 8192000000 10024195152 10409496576 1079050752 11.638 100 71 138 1 1150080224 56907072 14122400
05:32:40 905 8192000000 10046985824 10431627264 1082485488 11.671 100 87 171 0 1168708384 69250224 16972672
05:33:42 1822 8192000000 10041514032 10422915072 1088538816 11.729 100 88 163 0 1158284704 53892720 15853168
05:34:45 1420 8192000000 10046482944 10435338240 1094542160 11.786 100 89 167 3 1167141296 55856416 16742720
05:35:51 1081 8192000000 10063303888 10454368256 1097458432 11.814 100 92 177 1 1189868944 73582832 18827680
05:36:53 1141 8192000000 10071854336 10459721728 1099329536 11.831 100 93 172 0 1196298016 79632112 17336368
05:37:56 1126 8192000000 10056195408 10446946304 1097616480 11.815 100 85 163 0 1176262784 62921248 15725056
05:38:58 1503 8192000000 10038589456 10423390208 1083651888 11.682 100 94 180 1 1161874576 58905552 19317136
05:40:01 1380 8192000000 10033342224 10425724928 1087322384 11.717 100 74 146 1 1168556112 66494224 14739504
05:41:04 1292 8192000000 10017287584 10398429184 1062555440 11.481 100 85 158 1 1143442304 63861264 17025600
05:42:07 722 8192000000 10035305184 10416472064 1072373200 11.575 100 96 183 0 1151836896 61000304 18463392
05:43:09 1304 8192000000 10002022272 10379509760 1042004928 11.284 100 84 158 2 1123419808 65800640 15614240
05:44:13 936 8192000000 10003399088 10380693504 1047934128 11.341 100 85 164 2 1125863632 61538336 16391168
05:45:15 2425 8192000000 9982077648 10367410176 1041401024 11.278 100 52 102 0 1101059440 49711584 9946832
05:46:20 1711 8192000000 10002035776 10374451200 1051606544 11.376 100 88 156 2 1115623248 48412880 15603824
05:47:25 1352 8192000000 10009428320 10386763776 1067609584 11.529 100 94 170 1 1127021904 41919216 17493104
05:48:33 1368 8192000000 10052441584 10434273280 1081370528 11.661 100 81 159 1 1175018576 76641680 17006368
05:49:40 8191 8192000000 10056182208 10453483520 1085985936 11.704 100 64 126 0 1183808992 84783376 13039680
05:50:57 17468 8192000000 10075422224 10474393600 1095648000 11.796 100 56 107 1 1194389760 87363856 11377904
05:52:22 2499 8192000000 10073168448 10463301632 1099395936 11.832 100 80 142 4 1189540000 74659456 15484608
05:53:29 5963 8192000000 10107711936 10507390976 1123832720 12.063 100 64 122 0 1232114288 95408880 12872688
05:54:42 6622 8192000000 10179617056 10572337152 1139492560 12.211 100 73 138 0 1294995152 140909504 14593088
05:55:53 4122 8192000000 10202149520 10601590784 1151439248 12.323 100 75 148 0 1316434224 149553488 15441488
05:57:03 2135 8192000000 10259886032 10662825984 1157812128 12.383 100 90 179 0 1371086544 193840992 19433424
05:58:08 1131 8192000000 10278601952 10678960128 1175182720 12.545 100 93 183 2 1388423392 191798560 21442112
05:59:13 6049 8192000000 10295396880 10703736832 1189659792 12.680 100 74 146 2 1420622960 214604768 16358400
06:00:27 989 8192000000 10340560288 10747383808 1193638928 12.717 100 93 172 1 1452923936 239473248 19811760
06:01:32 2155 8192000000 10380824800 10785333248 1196166896 12.741 100 89 173 0 1487321200 272539984 18614320
06:02:39 1232 8192000000 10420203728 10843365376 1198432752 12.762 100 98 188 1 1526698240 305715616 22549872
06:03:43 1820 8192000000 10456875104 10867466240 1203792624 12.812 100 87 166 0 1565263104 342477024 18993456
06:04:50 921 8192000000 10496758656 10909822976 1201438176 12.790 100 96 183 1 1599989952 377732848 20818928
06:05:54 1780 8192000000 10548715120 10959187968 1196678784 12.745 100 89 174 0 1657178288 439456480 21043024
06:07:00 1002 8192000000 10544774448 10987892736 1190706784 12.690 100 94 178 1 1653543184 442329616 20506784
06:08:05 1017 8192000000 10558400208 11008045056 1186180768 12.648 100 94 184 0 1665001008 455738496 23081744
06:09:09 1881 8192000000 10573546336 11002744832 1181861744 12.608 100 97 187 0 1685295264 480362704 23070816
06:10:16 4869 8192000000 10507155600 10980507648 1182994416 12.618 100 65 130 0 1616145584 416721216 16429952
06:11:29 1410 8192000000 10419375520 10920341504 1174622832 12.540 100 90 173 1 1518986256 323050848 21312576
06:12:32 2481 8192000000 10413088656 10915418112 1173293632 12.528 100 82 162 0 1538779808 345917552 19568624
06:13:39 4128 8192000000 10353307168 10788122624 1151958464 12.328 98 45 86 2 1461406832 298329760 11118608
06:14:45 5539 8192000000 10311803136 10767077376 1160473728 12.408 100 41 82 0 1418127504 247049104 10604672
06:16:09 27421 8192000000 10245839664 10698387456 1142906240 12.243 99 23 44 0 1345547120 196781584 5859296
06:17:38 13213 8192000000 10187497488 10681085952 1147946432 12.290 99 36 69 0 1312330896 156229328 8155136
06:19:01 19611 8192000000 10167219136 10598318080 1146928544 12.281 98 46 90 1 1247054720 91190576 8935600
06:20:24 7618 8192000000 10158425824 10603008000 1159789152 12.401 100 53 100 1 1261476816 87778736 13908928
06:21:33 29738 8192000000 10199168144 10672992256 1152450368 12.332 99 22 42 0 1313509248 155832496 5226384
06:23:07 39063 8192000000 10162752512 10663370752 1153083744 12.338 100 14 28 0 1282988736 126906096 2998896
06:24:54 62178 8192000000 10089250176 10529480704 1118487088 12.013 98 32 62 1 1165619440 40039360 7092992
06:27:00 37029 8192000000 10102901376 10565332992 1139191808 12.208 100 30 55 1 1225850640 80945344 5713488
06:28:39 111454 8192000000 10121069408 10591330304 1136727536 12.185 99 27 47 1 1211428368 69501920 5198912
06:31:31 57038 8192000000 10122446928 10592567296 1137271568 12.190 99 25 50 0 1211353696 68916640 5165488
06:33:31 11494 8192000000 10076553376 10516725760 1130469744 12.126 99 19 33 0 1166332960 32697584 3165632
06:34:45 7729 8192000000 10167811584 10599251968 1152415936 12.332 100 56 111 0 1263998544 100121952 11460656
06:35:59 1892 8192000000 10108404656 10545815552 1134533088 12.164 100 53 93 1 1207017968 61963008 10521872
06:37:04 6710 8192000000 10128758560 10555785216 1129104128 12.113 100 57 111 1 1221500256 79898320 12497808
06:38:15 35534 8192000000 10106457152 10604556288 1115024960 11.980 99 15 30 0 1209660128 90817360 3817808
06:39:52 119395 8192000000 10127804928 10589773824 1105485072 11.890 97 22 44 1 1181474304 70853104 5136128
06:43:08 59285 8192000000 10098570976 10541649920 1127701344 12.100 99 28 54 0 1185720416 52771712 5247360
06:45:08 60809 8192000000 10086798944 10559602688 1137152032 12.189 100 16 30 0 1195722976 55008080 3562864
06:47:34 82845 8192000000 10090684064 10546196480 1124182128 12.066 98 10 19 0 1156843200 30923616 1737456
06:49:58 20906 8192000000 10103321808 10545680384 1141357040 12.228 99 33 63 0 1198807008 51634720 5815248
06:51:23 38205 8192000000 10117773056 10575581184 1145133648 12.264 99 32 60 0 1211173376 60094784 5944944
06:53:02 53444 8192000000 10178698736 10618159104 1139901952 12.215 98 31 62 0 1247464544 101316768 6245824
06:54:59 31305 8192000000 10231988864 10670292992 1136437728 12.182 98 36 72 0 1296879920 152652864 7789328
06:56:55 23846 8192000000 10209714896 10651488256 1136624784 12.184 100 37 74 1 1303153264 158708832 7819648
06:58:22 36517 8192000000 10185056912 10683367424 1138791120 12.204 99 25 50 0 1289895600 145249376 5855104
07:00:01 65774 8192000000 10153613216 10632876032 1114327648 11.973 98 20 40 0 1231707792 112921200 4458944
07:02:07 35844 8192000000 10078199344 10557906944 1122597648 12.052 100 15 27 0 1194736448 69504080 2634720
07:03:52 104720 8192000000 10103496192 10542555136 1101227056 11.849 97 28 56 0 1162312832 55373120 5712656
07:06:39 53211 8192000000 10063870480 10549891072 1091915392 11.761 97 26 50 1 1164357728 66262208 6180128
07:08:38 90734 8192000000 10046317264 10525372416 1104885680 11.884 98 6 13 0 1139432128 33670608 875840
07:11:10 55284 8192000000 10045215296 10505994240 1125006960 12.074 100 13 26 0 1151728128 23869248 2851920
07:13:05 42245 8192000000 10054297088 10515808256 1120893936 12.035 100 11 22 0 1166173376 43174560 2104880
07:14:51 67201 8192000000 10135250080 10599362560 1131801776 12.138 100 20 40 0 1217042464 80563136 4677552
07:17:00 80161 8192000000 10146546912 10593595392 1127119136 12.094 99 10 20 0 1234718160 105171168 2427856
07:19:26 59974 8192000000 10121529632 10575056896 1123972848 12.065 98 24 48 2 1215202768 86465536 4764384
07:21:26 50511 8192000000 10102753168 10551361536 1107820640 11.912 97 21 40 3 1151495840 39475376 4199824
07:23:29 25684 8192000000 10146990784 10582646784 1125961088 12.083 98 35 70 0 1196421024 63297968 7161968
07:24:58 12053 8192000000 10146549936 10604720128 1127599152 12.099 99 50 100 1 1243086768 104955840 10531776
07:26:38 29904 8192000000 10130529440 10586132480 1114460800 11.975 99 55 110 0 1192544016 67350736 10732480
07:28:09 5590 8192000000 10094361200 10508840960 1123790128 12.063 100 75 142 0 1183994048 45332784 14871136
07:29:16 69395 8192000000 10114935376 10566148096 1132737968 12.147 100 72 143 0 1219352288 72324384 14289936
07:31:34 62783 8192000000 10065598544 10528231424 1126816064 12.091 99 11 22 0 1162292784 33391648 2085072
07:33:50 94590 8192000000 10166303600 10628624384 1141894560 12.233 99 10 20 0 1240458064 96280544 2282960
07:36:25 15995 8192000000 10196735696 10643177472 1132026256 12.140 98 33 66 0 1272974704 133122608 7825840
07:37:47 34282 8192000000 10181355568 10640023552 1131126480 12.132 98 42 84 0 1271587120 130962064 9498576
07:39:28 47930 8192000000 10195370672 10647199744 1144104032 12.254 100 33 66 1 1284623376 132470240 8049104
07:41:41 19651 8192000000 10166233696 10616852480 1137994576 12.197 100 39 78 0 1257824416 111148560 8681280
07:43:04 45549 8192000000 10157008704 10658758656 1128137504 12.104 99 24 47 0 1242100928 108810400 5153024
07:44:56 68396 8192000000 10075525904 10534596608 1124050096 12.065 99 10 20 0 1191023520 65072016 1901408
07:47:11 12637 8192000000 9071658240 9309253632 225448608 2.678 20 5 10 0 237882944 11522032 912304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-10-2022 07:49:04 to 08-10-2022 07:49:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 91 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:03, from 08-10-2022 07:49:21 to 08-10-2022 07:49:24.

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          219169
MON$OLDEST_ACTIVE               220885
MON$OLDEST_SNAPSHOT             220885
MON$NEXT_TRANSACTION            220885
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                       2062624
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

08.10.22 07:49:25. 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-10-08 07:49:27 No severe PSQL-related problems occured
08.10.22 07:49:29. End of report.