Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2023 07: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 14318076 kB
MemAvailable 30433100 kB
Buffers 0 kB
Cached 17392348 kB
SwapCached 10260 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-01-01 07:37:22
DTS_END                         2023-01-01 07:37:22.8100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51342
TRN_ID                          224984
ATT_ID                          653
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: 8962.90 30831 1075548
customer order: creation 173.91 478 20869
customer order: refuse 15.14 468 1817
order to supplier: creation 46.10 1443 5532
order to supplier: removal 3.30 185223 396
invoice (draft): creation 47.25 3356 5670
invoice (draft): removal 49.38 1070 5926
invoice accept: apply 43.00 26502 5160
invoice accept: cancel 44.00 1335 5280
customer reserve: creation 3909.17 251 469100
customer reserve: removal 4332.47 109 519896
realization accept: apply 83.49 325 10019
realization accept: cancel 83.23 174 9987
payment from customer: creation 60.85 200 7302
payment from customer: removal 5.58 146 669
payment to supplier: creation 49.90 218 5988
payment to supplier: removal 3.63 146 436
service: total inventory turnovers 3.46 5639 415
service: total monetary turnovers 8.13 419 976
service: aggregate perf. data 0.88 21454 106
service: refresh index statistics 0.03 367664 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2023 07:39:48 to 01-01-2023 07:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7467
TEST_TIME 2 6306
TEST_TIME 3 5914
TEST_TIME 4 5611
TEST_TIME 5 5834
TEST_TIME 6 6329
TEST_TIME 7 6518
TEST_TIME 8 6372
TEST_TIME 9 6640
TEST_TIME 10 7003
TEST_TIME 11 6820
TEST_TIME 12 7081
TEST_TIME 13 7110
TEST_TIME 14 7231
TEST_TIME 15 7425
TEST_TIME 16 7575
TEST_TIME 17 7543
TEST_TIME 18 7604
TEST_TIME 19 7689
TEST_TIME 20 7686
TEST_TIME 21 7951
TEST_TIME 22 8073
TEST_TIME 23 8170
TEST_TIME 24 8089
TEST_TIME 25 8314
TEST_TIME 26 8296
TEST_TIME 27 8675
TEST_TIME 28 8755
TEST_TIME 29 8907
TEST_TIME 30 9243
TEST_TIME 31 9325
TEST_TIME 32 9465
TEST_TIME 33 9397
TEST_TIME 34 9602
TEST_TIME 35 9526
TEST_TIME 36 9638
TEST_TIME 37 10184
TEST_TIME 38 10255
TEST_TIME 39 10480
TEST_TIME 40 10758
TEST_TIME 41 11194
TEST_TIME 42 11179
TEST_TIME 43 10936
TEST_TIME 44 10800
TEST_TIME 45 10753
TEST_TIME 46 10787
TEST_TIME 47 10623
TEST_TIME 48 10488
TEST_TIME 49 10410
TEST_TIME 50 10364
TEST_TIME 51 10318
TEST_TIME 52 10183
TEST_TIME 53 10196
TEST_TIME 54 10114
TEST_TIME 55 10058
TEST_TIME 56 10108
TEST_TIME 57 10003
TEST_TIME 58 9974
TEST_TIME 59 9969
TEST_TIME 60 9988
TEST_TIME 61 9909
TEST_TIME 62 9891
TEST_TIME 63 9820
TEST_TIME 64 9675
TEST_TIME 65 9658
TEST_TIME 66 9659
TEST_TIME 67 9698
TEST_TIME 68 9602
TEST_TIME 69 9518
TEST_TIME 70 9475
TEST_TIME 71 9450
TEST_TIME 72 9412
TEST_TIME 73 9384
TEST_TIME 74 9414
TEST_TIME 75 9446
TEST_TIME 76 9394
TEST_TIME 77 9318
TEST_TIME 78 9345
TEST_TIME 79 9267
TEST_TIME 80 9208
TEST_TIME 81 9193
TEST_TIME 82 9166
TEST_TIME 83 9109
TEST_TIME 84 9125
TEST_TIME 85 9092
TEST_TIME 86 9085
TEST_TIME 87 9098
TEST_TIME 88 9103
TEST_TIME 89 9087
TEST_TIME 90 9089
TEST_TIME 91 9075
TEST_TIME 92 9043
TEST_TIME 93 9166
TEST_TIME 94 9153
TEST_TIME 95 9141
TEST_TIME 96 9158
TEST_TIME 97 9172
TEST_TIME 98 9225
TEST_TIME 99 9182
TEST_TIME 100 9136
TEST_TIME 101 9097
TEST_TIME 102 9049
TEST_TIME 103 9021
TEST_TIME 104 8993
TEST_TIME 105 8936
TEST_TIME 106 8876
TEST_TIME 107 8864
TEST_TIME 108 8858
TEST_TIME 109 8874
TEST_TIME 110 8941
TEST_TIME 111 8901
TEST_TIME 112 8948
TEST_TIME 113 8964
TEST_TIME 114 8990
TEST_TIME 115 9053
TEST_TIME 116 9016
TEST_TIME 117 9044
TEST_TIME 118 9061
TEST_TIME 119 9062
TEST_TIME 120 8990

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-01-2023 07:39:51 to 01-01-2023 07:39:55.

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 1506904 1506904 0 0.00 0 21292 14 0 0
doc_list_biud 1506861 1506861 0 0.00 0 43522 44 0 0
sp_add_invoice_to_stock 5352 5160 192 3.58 45 187927 26503 0 192
sp_cancel_adding_invoice 5280 5280 0 0.00 5 84954 1336 0 0
sp_cancel_client_order 1817 1817 0 0.00 6 11816 468 0 0
sp_cancel_customer_reserve 519896 519896 0 0.00 1 43581 109 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 3256 146 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 4 6409 146 0 0
sp_cancel_supplier_invoice 5939 5926 13 0.21 2 11215 1070 0 13
sp_cancel_supplier_order 396 396 0 0.00 13 1784691 185224 0 0
sp_cancel_write_off 9987 9987 0 0.00 2 9895 175 0 0
sp_client_order 20869 20869 0 0.00 6 38596 478 0 0
sp_customer_reserve 469100 469100 0 0.00 0 154307 252 0 0
sp_fill_shopping_cart 32066 32066 0 0.00 1 11537 103 0 0
sp_fill_shopping_cart_clo_res 469052 469052 0 0.00 0 15314 20 0 0
sp_get_clo_for_invoice 5105 5105 0 0.00 0 28449 349 0 0
sp_kill_cost_storno 16372 16372 0 0.00 0 8115 81 0 0
sp_kill_qstorno_ret_qs2qd 1056101 1056101 0 0.00 0 43441 46 0 0
sp_kill_qty_storno 558425 558425 0 0.00 0 43518 109 0 0
sp_lock_selected_doc 20887 20887 0 0.00 0 6952 9 0 0
sp_make_cost_storno 28414 28414 0 0.00 0 14136 94 0 0
sp_multiply_rows_for_pdistr 28413 28413 0 0.00 0 9205 38 0 0
sp_multiply_rows_for_qdistr 20869 20869 0 0.00 0 38488 177 0 0
sp_pay_from_customer 7302 7302 0 0.00 5 13302 200 0 0
sp_pay_to_supplier 5988 5988 0 0.00 7 12428 218 0 0
sp_payment_common 13290 13290 0 0.00 2 13140 56 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 11808 382 0 0
sp_qd_handle_on_invoice_upd_sts 10384 10384 0 0.00 0 43016 658 0 0
sp_qd_handle_on_reserve_upd_sts 10019 10019 0 0.00 0 17683 76 0 0
sp_reserve_write_off 10019 10019 0 0.00 7 19000 326 0 0
sp_supplier_invoice 5670 5670 0 0.00 9 65119 3357 0 0
sp_supplier_order 5532 5532 0 0.00 7 59059 1444 0 0
srv_aggregate_perf_data 106 106 0 0.00 2779 315633 21455 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 736 170046 33675 0 0
srv_find_qd_qs_mism 1025472 1025472 0 0.00 0 21288 10 0 0
srv_make_invnt_saldo 415 415 0 0.00 45 112221 5640 0 0
srv_make_money_saldo 976 976 0 0.00 1 11388 419 0 0
srv_recalc_idx_stat 4 4 0 0.00 207660 503811 367665 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5160 5160 0 0.00 0 11900 1170 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 2 7449 553 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 5612 32 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 740 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 354 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 884 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3137 55 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 125059 9021 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 3311 24 0 0
t$perf-norm:sp_client_order 20869 20869 0 0.00 0 11939 42 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 1172 14 0 0
t$perf-norm:sp_pay_from_customer 7302 7302 0 0.00 0 11020 25 0 0
t$perf-norm:sp_pay_to_supplier 5988 5988 0 0.00 0 3322 22 0 0
t$perf-norm:sp_reserve_write_off 10019 10019 0 0.00 0 9339 27 0 0
t$perf-norm:sp_supplier_invoice 5670 5670 0 0.00 0 3753 35 0 0
t$perf-norm:sp_supplier_order 5532 5532 0 0.00 0 4499 37 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 454 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 95 6 0 0
t$perf-norm:srv_make_invnt_saldo 415 415 0 0.00 0 614 14 0 0
t$perf-norm:srv_make_money_saldo 976 976 0 0.00 0 448 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 137 53 0 0
v_add_invoice_to_stock 10590 10206 384 3.62 0 5704 33 0 384
v_all_customers 38620 38620 0 0.00 0 141 5 0 0
v_all_suppliers 30904 30904 0 0.00 0 153 5 0 0
v_all_wares 41738 41738 0 0.00 0 139 5 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 232 16 0 0
v_cancel_client_order 3634 3634 0 0.00 0 3105 14 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 3169 19 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 1 294 12 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 2647 17 0 0
v_cancel_supplier_invoice 834 808 26 3.11 0 738 32 0 26
v_cancel_supplier_order 792 792 0 0.00 0 1431 15 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2928 37 0 0
v_max_id_clo_ord 5532 5532 0 0.00 0 4658 8 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 506 9 0 0
v_max_id_ord_sup 5665 5665 0 0.00 0 5396 9 0 0
v_max_non_paid_invoice 1733 1733 0 0.00 0 2987 11 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 4678 11 0 0
v_min_id_clo_ord 5532 5532 0 0.00 0 4818 15 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 2518 18 0 0
v_min_id_ord_sup 5665 5665 0 0.00 0 3222 9 0 0
v_min_non_paid_invoice 5988 5988 0 0.00 0 11654 20 0 0
v_min_non_paid_realizn 7302 7302 0 0.00 0 13119 25 0 0
v_our_firm 10288 10288 0 0.00 0 151 5 0 0
v_reserve_write_off 20038 20038 0 0.00 0 4178 13 0 0
x_lock_depdocs_on_canc_invoice 10802 10802 0 0.00 0 19206 139 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 8990 449 0 0
x_make_qstorno_1000_1200 5532 5532 0 0.00 3 58983 1213 0 0
x_make_qstorno_1200_2000 5670 5670 0 0.00 4 64631 3099 0 0
x_make_qstorno_2100_3300 469100 469100 0 0.00 0 154301 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2023 07:39:56 to 01-01-2023 07:40:00.

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:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 35859 8192000000 9735145120 10034913280 808260496 8.980 100 89 176 1 837626112 7946896 21418720
05:10:07 10024 8192000000 9773538960 10084438016 849732864 9.397 100 88 172 0 881884640 11757552 20394224
05:11:27 6871 8192000000 9811535808 10129575936 879380432 9.694 100 87 174 0 919400400 20116576 19903392
05:12:39 13803 8192000000 9868984320 10199433216 914511248 10.042 100 82 162 1 969697248 37152784 18033216
05:13:56 4322 8192000000 9908345840 10250592256 957066480 10.460 100 85 167 0 1016544400 41826224 17651696
05:15:04 2384 8192000000 9948572784 10299473920 984983504 10.733 100 91 181 1 1067153680 63493136 18677040
05:16:08 1418 8192000000 9985170416 10342768640 1008076240 10.957 100 85 167 1 1107249168 82822608 16350320
05:17:12 1025 8192000000 10018998816 10384277504 1029214480 11.161 100 96 186 1 1139812000 92306704 18290816
05:18:15 1380 8192000000 10007233440 10378317824 1053670768 11.396 100 95 187 0 1127800048 55309888 18819392
05:19:17 1070 8192000000 10027675760 10407514112 1068083792 11.534 100 91 175 0 1152554688 66244272 18226624
05:20:20 975 8192000000 10048058656 10431361024 1072828800 11.579 100 97 188 1 1168667712 77221888 18617024
05:21:22 917 8192000000 10043230464 10431840256 1075731920 11.607 100 82 158 2 1162273472 69977376 16564176
05:22:24 1025 8192000000 10054823808 10443587584 1081229808 11.659 100 91 173 1 1179777168 79787392 18759968
05:23:27 1087 8192000000 10064044608 10449145856 1085321376 11.698 100 95 177 2 1182917920 79872848 17723696
05:24:30 3192 8192000000 10057409232 10450886656 1088999216 11.733 100 78 154 1 1184218000 80357600 14861184
05:25:35 4776 8192000000 10049463008 10436587520 1091366672 11.756 100 79 144 0 1172438432 68194832 12876928
05:26:40 1019 8192000000 10055369168 10445119488 1090248272 11.745 100 94 180 0 1179160864 69074608 19837984
05:27:43 2783 8192000000 10040023360 10433982464 1099151744 11.830 100 66 123 1 1164055680 52521328 12382608
05:28:47 1052 8192000000 10067852816 10462183424 1096895488 11.808 100 97 182 0 1190232128 73474576 19862064
05:29:49 1070 8192000000 10074315120 10463567872 1098835712 11.827 100 92 161 0 1195679504 80191728 16652064
05:30:51 1115 8192000000 10061382064 10450718720 1091053616 11.753 100 95 178 0 1184003152 75298768 17650768
05:31:53 1136 8192000000 10016627072 10393731072 1048282176 11.344 99 93 179 2 1140575120 72908912 19384032
05:32:55 1893 8192000000 9995465104 10369564672 1038487424 11.250 98 81 148 1 1121157312 67064896 15604992
05:33:58 1540 8192000000 9986072848 10366152704 1028316752 11.152 98 74 138 1 1100585808 57801520 14467536
05:35:01 885 8192000000 9991822480 10369310720 1044979712 11.313 100 88 162 0 1111477344 49686480 16811152
05:36:03 4926 8192000000 9980972608 10360045568 1027306320 11.142 98 62 111 0 1093452096 55542336 10603440
05:37:09 1142 8192000000 10006975952 10383118336 1047084080 11.333 100 95 177 3 1127447680 62395424 17968176
05:38:16 1070 8192000000 10021649984 10399633408 1048273584 11.344 100 88 160 1 1138935584 74794592 15867408
05:39:22 16610 8192000000 10022745984 10406285312 1065818128 11.512 100 68 126 1 1143978912 65553120 12607664
05:40:43 25824 8192000000 10030352864 10418925568 1081841968 11.665 100 63 123 0 1153063744 58344816 12876960
05:42:12 965 8192000000 10097112160 10473705472 1084349920 11.689 99 90 172 1 1208342912 106355520 17637472
05:43:17 1555 8192000000 10110487232 10499112960 1104018384 11.876 100 90 175 0 1230729312 107979264 18731664
05:44:25 1231 8192000000 10146622144 10538328064 1123011072 12.055 100 80 145 3 1266765568 128336000 15418496
05:45:30 5014 8192000000 10174494896 10575716352 1130466096 12.126 100 76 150 1 1291008224 144427392 16114736
05:46:41 990 8192000000 10205792944 10607857664 1143519104 12.249 100 98 195 1 1328751936 163847648 21385184
05:47:44 1396 8192000000 10224345584 10624966656 1157971200 12.384 100 90 167 3 1339481728 162936992 18573536
05:48:49 4995 8192000000 10276808816 10688172032 1162643616 12.428 100 62 120 0 1387495904 211463648 13388640
05:50:03 1297 8192000000 10261304560 10668347392 1173521504 12.530 100 79 154 0 1377875824 186796752 17557568
05:51:11 2328 8192000000 10293925840 10707222528 1179975264 12.590 100 85 161 0 1405158880 207147600 18036016
05:52:15 1725 8192000000 10296968976 10708905984 1169683552 12.494 100 91 173 0 1407745280 219659872 18401856
05:53:19 1174 8192000000 10317127632 10725990400 1185201168 12.639 100 93 178 1 1429049040 223192224 20655648
05:54:23 908 8192000000 10342781280 10742501376 1178138320 12.573 100 95 188 0 1450943584 252002928 20802336
05:55:28 908 8192000000 10391840832 10797617152 1180711232 12.597 100 97 188 0 1498696992 296291344 21694416
05:56:32 800 8192000000 10425976640 10830823424 1182403168 12.613 100 98 190 0 1532496544 327328624 22764752
05:57:35 2302 8192000000 10455190464 10873081856 1183697808 12.625 100 94 185 0 1559451616 354119536 21634272
05:58:42 858 8192000000 10467484000 10873774080 1175961600 12.553 99 99 194 0 1570657504 371964304 22731600
05:59:45 1795 8192000000 10481778912 10910351360 1174180816 12.536 100 97 187 1 1586383632 389617344 22585472
06:00:49 2656 8192000000 10477722448 10898837504 1168574464 12.484 99 97 188 0 1574632000 384456896 21600640
06:01:56 759 8192000000 10520657312 10954674176 1176120080 12.554 100 93 183 1 1625699504 427354640 22224784
06:03:00 1007 8192000000 10485094528 10933469184 1169244720 12.490 100 96 180 2 1585492928 395007632 21240576
06:04:04 741 8192000000 10476570752 10959503360 1163204672 12.433 100 96 188 1 1579365328 392910224 23250432
06:05:08 1477 8192000000 10495171728 10923659264 1174823072 12.542 100 96 181 0 1595555808 399786128 20946608
06:06:13 1082 8192000000 10458289040 10940243968 1178516688 12.576 100 92 177 0 1562819968 362234624 22068656
06:07:19 731 8192000000 10418930112 10877009920 1155317232 12.359 99 68 127 2 1522289840 351323376 15649232
06:08:23 6170 8192000000 10452445744 10873745408 1162464352 12.426 99 56 112 1 1556639200 380449344 13725504
06:09:30 1963 8192000000 10492878640 10942087168 1173405296 12.529 100 84 164 0 1591495216 398682704 19407216
06:10:37 1405 8192000000 10507867552 10925514752 1176381248 12.556 100 91 175 0 1610852416 412287248 22183920
06:11:40 6218 8192000000 10493114928 10972884992 1161339952 12.416 99 46 92 1 1589773472 416175984 12257536
06:12:49 33677 8192000000 10469279024 10982084608 1142718256 12.241 98 42 82 0 1595191072 441809744 10663072
06:14:27 41513 8192000000 10383236448 10837942272 1144132752 12.254 99 28 55 0 1471413392 320295904 6984736
06:16:19 9778 8192000000 10323699216 10776084480 1139786848 12.214 100 32 64 0 1417789232 269092496 8909888
06:17:30 39660 8192000000 10181585728 10681745408 1132019296 12.140 99 18 36 0 1321442560 185126608 4296656
06:19:10 79127 8192000000 10095675952 10577113088 1141754640 12.232 100 26 50 1 1243589840 96539168 5296032
06:21:31 6989 8192000000 10159170192 10590502912 1148895520 12.299 100 50 100 0 1248521936 88938352 10688064
06:22:45 28603 8192000000 10087805472 10592112640 1145778080 12.270 100 16 32 0 1212593472 63565856 3249536
06:24:16 40658 8192000000 10077134816 10509713408 1098447024 11.823 96 17 33 0 1137892240 36030192 3415024
06:26:01 32397 8192000000 10123648816 10570571776 1142621312 12.240 100 29 58 2 1207929456 58671152 6636992
06:27:34 86725 8192000000 10081961456 10553135104 1090362848 11.746 96 15 30 0 1125804304 32606224 2835232
06:30:11 72584 8192000000 10096893344 10551222272 1118553456 12.013 99 23 41 1 1169782608 47701296 3527856
06:32:34 113013 8192000000 10109786448 10590412800 1114988736 11.980 99 12 24 0 1176587680 59512384 2086560
06:36:01 83958 8192000000 10083617584 10554089472 1088019648 11.724 96 21 40 1 1147442896 54686272 4736976
06:38:27 98787 8192000000 10073267824 10546753536 1081105696 11.658 96 5 10 0 1107481152 25587024 788432
06:41:10 170046 8192000000 10115481216 10612293632 1128687600 12.109 99 23 44 0 1195527232 62230384 4609248
06:45:11 127486 8192000000 10067677376 10490540032 1085251168 11.697 95 46 85 1 1138298976 44984416 8063392
06:48:21 39755 8192000000 10088413168 10525057024 1117858640 12.007 98 46 83 1 1167087872 40130800 9098432
06:50:04 7561 8192000000 10128486352 10544582656 1119975920 12.027 99 73 138 2 1222928336 89149024 13803392
06:51:12 43694 8192000000 10118514752 10586550272 1118992800 12.017 99 31 62 0 1215096896 89585264 6518832
06:53:36 36939 8192000000 10081056464 10540457984 1109356240 11.926 98 10 20 0 1160985696 49822576 1806880
06:55:20 48226 8192000000 10046098816 10517868544 1113849872 11.969 99 19 37 0 1166863856 48930576 4083408
06:57:09 103118 8192000000 10067852320 10543230976 1090167856 11.744 97 26 51 1 1141272384 46415248 4689280
07:00:00 23942 8192000000 10074727712 10520043520 1125039440 12.075 99 21 38 2 1182832672 53849600 3943632
07:01:25 52547 8192000000 10089816336 10561335296 1115351168 11.983 98 15 30 0 1219022016 99865824 3805024
07:04:17 30971 8192000000 10077523264 10558705664 1135245136 12.171 100 14 28 0 1175254464 36809584 3199744
07:05:52 19438 8192000000 10069698336 10522120192 1120616384 12.033 99 38 68 1 1154682464 26653760 7412320
07:07:20 18344 8192000000 10063248672 10490421248 1105574608 11.891 98 65 122 1 1147369632 29149648 12645376
07:08:47 15796 8192000000 10139103968 10583601152 1120693184 12.034 99 33 66 0 1212167696 84740496 6734016
07:10:05 60256 8192000000 10060500112 10514083840 1104175088 11.877 98 25 50 0 1205123984 95728864 5220032
07:12:06 23019 8192000000 10093946656 10546126848 1116773424 11.996 99 40 80 0 1181670192 57190048 7706720
07:13:30 71701 8192000000 10084497680 10533142528 1112825200 11.959 98 14 28 0 1167891584 52198112 2868272
07:15:46 59010 8192000000 10098212080 10543951872 1105391344 11.889 98 38 72 0 1161134928 48342112 7401472
07:18:35 81691 8192000000 10058944048 10488463360 1071568400 11.567 95 36 68 0 1131190048 52733776 6887872
07:20:58 49008 8192000000 10042324896 10509033472 1089932656 11.742 100 15 30 0 1155411456 62250928 3227872
07:22:47 68871 8192000000 10117333040 10560942080 1104073280 11.876 99 16 32 0 1189223488 81465392 3684816
07:25:16 67548 8192000000 10136670816 10600992768 1109286176 11.926 98 21 40 0 1218968000 104966880 4714944
07:27:45 44006 8192000000 10114784944 10557255680 1133921072 12.158 100 22 43 0 1216330864 77883712 4526080
07:29:29 47823 8192000000 10082907968 10531180544 1089800976 11.741 96 22 44 0 1136840736 42196576 4843184
07:31:23 100979 8192000000 10064386016 10508570624 1107146320 11.905 97 20 41 0 1173643088 61459952 5036816
07:34:09 61752 8192000000 10117879632 10547671040 1094317584 11.784 96 36 70 0 1183753744 82413488 7022672
07:36:11 85282 8192000000 9613520784 10007097344 725482272 8.135 64 21 42 0 765954576 36670944 3801360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-01-2023 07:40:02 to 01-01-2023 07:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 192 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2023 07:40:19 to 01-01-2023 07:40:21.

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          224911
MON$OLDEST_ACTIVE               225166
MON$OLDEST_SNAPSHOT             225166
MON$NEXT_TRANSACTION            225166
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                       2154944
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:794: 
:795: mscs-test-161.budzdorov.ru    Sun Jan  1 07:38:06 2023
:796:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:797: 
--- end of diff output ---

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

01.01.23 07:40:22. 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-01-01 07:40:25 No severe PSQL-related problems occured
01.01.23 07:40:26. End of report.