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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14913336 kB
MemAvailable 30411400 kB
Buffers 0 kB
Cached 16761040 kB
SwapCached 3040 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-03-12 13:37:22
DTS_END                         2023-03-12 13:37:22.8160
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/56846
TRN_ID                          220697
ATT_ID                          603
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: 9255.44 21175 1110653
customer order: creation 173.19 459 20783
customer order: refuse 14.16 388 1699
order to supplier: creation 45.79 1326 5495
order to supplier: removal 3.55 161488 426
invoice (draft): creation 45.78 2940 5493
invoice (draft): removal 52.58 957 6309
invoice accept: apply 41.08 24542 4929
invoice accept: cancel 47.78 1250 5733
customer reserve: creation 3838.23 230 460588
customer reserve: removal 4697.00 95 563640
realization accept: apply 83.50 284 10020
realization accept: cancel 83.35 167 10002
payment from customer: creation 58.94 173 7073
payment from customer: removal 5.79 130 695
payment to supplier: creation 48.72 205 5846
payment to supplier: removal 3.43 156 411
service: total inventory turnovers 4.12 3916 494
service: total monetary turnovers 7.48 359 898
service: aggregate perf. data 0.96 14205 115
service: refresh index statistics 0.03 210223 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2023 13:38:40 to 12-03-2023 13:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7675
TEST_TIME 2 6386
TEST_TIME 3 6598
TEST_TIME 4 7154
TEST_TIME 5 6969
TEST_TIME 6 6791
TEST_TIME 7 6927
TEST_TIME 8 7264
TEST_TIME 9 7118
TEST_TIME 10 6989
TEST_TIME 11 6975
TEST_TIME 12 7446
TEST_TIME 13 7431
TEST_TIME 14 7740
TEST_TIME 15 7737
TEST_TIME 16 7790
TEST_TIME 17 7743
TEST_TIME 18 7602
TEST_TIME 19 7593
TEST_TIME 20 7788
TEST_TIME 21 8011
TEST_TIME 22 7934
TEST_TIME 23 8101
TEST_TIME 24 8084
TEST_TIME 25 8294
TEST_TIME 26 8531
TEST_TIME 27 8641
TEST_TIME 28 8770
TEST_TIME 29 8813
TEST_TIME 30 8941
TEST_TIME 31 9166
TEST_TIME 32 9828
TEST_TIME 33 10639
TEST_TIME 34 10765
TEST_TIME 35 11226
TEST_TIME 36 11168
TEST_TIME 37 10963
TEST_TIME 38 10957
TEST_TIME 39 10961
TEST_TIME 40 10751
TEST_TIME 41 10681
TEST_TIME 42 10711
TEST_TIME 43 10801
TEST_TIME 44 10668
TEST_TIME 45 10716
TEST_TIME 46 10689
TEST_TIME 47 10614
TEST_TIME 48 10488
TEST_TIME 49 10279
TEST_TIME 50 10174
TEST_TIME 51 10086
TEST_TIME 52 9979
TEST_TIME 53 9963
TEST_TIME 54 9990
TEST_TIME 55 10039
TEST_TIME 56 10033
TEST_TIME 57 9948
TEST_TIME 58 9890
TEST_TIME 59 9846
TEST_TIME 60 9827
TEST_TIME 61 9698
TEST_TIME 62 9579
TEST_TIME 63 9659
TEST_TIME 64 9734
TEST_TIME 65 9679
TEST_TIME 66 9677
TEST_TIME 67 9720
TEST_TIME 68 9677
TEST_TIME 69 9690
TEST_TIME 70 9726
TEST_TIME 71 9743
TEST_TIME 72 9794
TEST_TIME 73 9832
TEST_TIME 74 9886
TEST_TIME 75 9908
TEST_TIME 76 9926
TEST_TIME 77 9839
TEST_TIME 78 9831
TEST_TIME 79 9785
TEST_TIME 80 9807
TEST_TIME 81 9773
TEST_TIME 82 9750
TEST_TIME 83 9700
TEST_TIME 84 9650
TEST_TIME 85 9627
TEST_TIME 86 9563
TEST_TIME 87 9493
TEST_TIME 88 9472
TEST_TIME 89 9414
TEST_TIME 90 9433
TEST_TIME 91 9437
TEST_TIME 92 9446
TEST_TIME 93 9420
TEST_TIME 94 9412
TEST_TIME 95 9454
TEST_TIME 96 9488
TEST_TIME 97 9477
TEST_TIME 98 9483
TEST_TIME 99 9463
TEST_TIME 100 9539
TEST_TIME 101 9574
TEST_TIME 102 9552
TEST_TIME 103 9634
TEST_TIME 104 9585
TEST_TIME 105 9531
TEST_TIME 106 9522
TEST_TIME 107 9478
TEST_TIME 108 9459
TEST_TIME 109 9431
TEST_TIME 110 9379
TEST_TIME 111 9369
TEST_TIME 112 9310
TEST_TIME 113 9306
TEST_TIME 114 9347
TEST_TIME 115 9355
TEST_TIME 116 9320
TEST_TIME 117 9350
TEST_TIME 118 9348
TEST_TIME 119 9302
TEST_TIME 120 9275

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-03-2023 13:38:44 to 12-03-2023 13:38:47.

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 1536137 1536137 0 0.00 0 18253 13 0 0
doc_list_biud 1536128 1536128 0 0.00 0 42561 40 0 0
sp_add_invoice_to_stock 5060 4929 131 2.58 41 212008 24542 0 131
sp_cancel_adding_invoice 5733 5733 0 0.00 6 97780 1250 0 0
sp_cancel_client_order 1699 1699 0 0.00 5 8612 388 0 0
sp_cancel_customer_reserve 563640 563640 0 0.00 1 30495 96 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 3819 131 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 12701 156 0 0
sp_cancel_supplier_invoice 6315 6309 6 0.09 3 19549 958 0 6
sp_cancel_supplier_order 426 426 0 0.00 14 1593159 161489 0 0
sp_cancel_write_off 10002 10002 0 0.00 2 12161 168 0 0
sp_client_order 20783 20783 0 0.00 6 28479 459 0 0
sp_customer_reserve 460588 460588 0 0.00 0 64179 231 0 0
sp_fill_shopping_cart 31771 31771 0 0.00 1 32449 86 0 0
sp_fill_shopping_cart_clo_res 460558 460558 0 0.00 0 22249 19 0 0
sp_get_clo_for_invoice 4891 4891 0 0.00 0 16845 285 0 0
sp_kill_cost_storno 16841 16841 0 0.00 0 18243 73 0 0
sp_kill_qstorno_ret_qs2qd 1144017 1144017 0 0.00 0 30442 41 0 0
sp_kill_qty_storno 602719 602719 0 0.00 0 42561 95 0 0
sp_lock_selected_doc 20638 20638 0 0.00 0 9889 9 0 0
sp_make_cost_storno 27830 27830 0 0.00 0 17959 86 0 0
sp_multiply_rows_for_pdistr 27829 27829 0 0.00 0 6192 38 0 0
sp_multiply_rows_for_qdistr 20783 20783 0 0.00 0 10645 172 0 0
sp_pay_from_customer 7073 7073 0 0.00 5 15827 173 0 0
sp_pay_to_supplier 5846 5846 0 0.00 6 17891 206 0 0
sp_payment_common 12918 12918 0 0.00 2 10837 46 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 8598 305 0 0
sp_qd_handle_on_invoice_upd_sts 10623 10623 0 0.00 0 42560 513 0 0
sp_qd_handle_on_reserve_upd_sts 10020 10020 0 0.00 0 17998 65 0 0
sp_reserve_write_off 10020 10020 0 0.00 8 18037 284 0 0
sp_supplier_invoice 5493 5493 0 0.00 8 101802 2940 0 0
sp_supplier_order 5495 5495 0 0.00 8 89193 1327 0 0
srv_aggregate_perf_data 115 115 0 0.00 2160 186686 14205 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 728 137273 38670 0 0
srv_find_qd_qs_mism 1062422 1062422 0 0.00 0 17293 9 0 0
srv_make_invnt_saldo 494 494 0 0.00 41 94433 3916 0 0
srv_make_money_saldo 898 898 0 0.00 1 20722 359 0 0
srv_recalc_idx_stat 4 4 0 0.00 62513 397451 210224 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4931 4931 0 0.00 0 13698 944 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 7324 502 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 3298 28 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 657 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 972 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 170 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 3934 43 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 68369 6537 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 5814 23 0 0
t$perf-norm:sp_client_order 20783 20783 0 0.00 0 9113 38 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 1111 14 0 0
t$perf-norm:sp_pay_from_customer 7073 7073 0 0.00 0 8773 20 0 0
t$perf-norm:sp_pay_to_supplier 5846 5846 0 0.00 0 2502 20 0 0
t$perf-norm:sp_reserve_write_off 10020 10020 0 0.00 0 6985 25 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 5257 32 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 4946 33 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 568 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 20 4 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 1428 17 0 0
t$perf-norm:srv_make_money_saldo 898 898 0 0.00 0 480 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 173 51 0 0
v_add_invoice_to_stock 10042 9780 262 2.60 0 7687 23 0 262
v_all_customers 38072 38072 0 0.00 0 117 4 0 0
v_all_suppliers 30880 30880 0 0.00 0 125 4 0 0
v_all_wares 41566 41566 0 0.00 0 131 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 450 14 0 0
v_cancel_client_order 3398 3398 0 0.00 0 4177 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3729 15 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 1 875 10 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 4458 22 0 0
v_cancel_supplier_invoice 808 796 12 1.48 0 2385 26 0 12
v_cancel_supplier_order 852 852 0 0.00 0 847 13 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3301 25 0 0
v_max_id_clo_ord 5495 5495 0 0.00 0 4452 6 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1961 12 0 0
v_max_id_ord_sup 5492 5492 0 0.00 0 5393 9 0 0
v_max_non_paid_invoice 1392 1392 0 0.00 0 568 8 0 0
v_max_non_paid_realizn 3570 3570 0 0.00 0 2025 7 0 0
v_min_id_clo_ord 5495 5495 0 0.00 0 5868 12 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 1455 10 0 0
v_min_id_ord_sup 5492 5492 0 0.00 0 4045 9 0 0
v_min_non_paid_invoice 5845 5845 0 0.00 0 10819 18 0 0
v_min_non_paid_realizn 7073 7073 0 0.00 0 5198 14 0 0
v_our_firm 10500 10500 0 0.00 0 112 5 0 0
v_reserve_write_off 20040 20040 0 0.00 0 5381 10 0 0
x_lock_depdocs_on_canc_invoice 11644 11644 0 0.00 0 26395 118 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 5311 327 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 3 89166 1148 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 3 101705 2713 0 0
x_make_qstorno_2100_3300 460588 460588 0 0.00 0 64177 199 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-03-2023 13:38:48 to 12-03-2023 13:38:53.

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
11:07:26 911 8192000000 8936103984 8993222656 34354832 0.417 7 6 11 0 35519040 239984 924224
11:08:28 21495 8192000000 9727525648 10025381888 801873584 8.915 100 87 174 0 831254352 8239600 21141168
11:09:54 17454 8192000000 9761214000 10068692992 837919280 9.279 100 90 180 0 871365440 12467088 20979072
11:11:25 15465 8192000000 9796078416 10115252224 866856784 9.569 100 84 168 0 905241872 19164176 19220912
11:12:45 9065 8192000000 9849561408 10181472256 906002240 9.958 100 77 154 0 970621632 47380912 17238480
11:13:56 4892 8192000000 9903548416 10243764224 937116272 10.265 100 84 165 0 1018704640 63852016 17736352
11:15:04 3014 8192000000 9958988192 10309976064 981411408 10.698 100 85 164 2 1073728928 75702896 16614624
11:16:08 1980 8192000000 9994023376 10349494272 1010300240 10.978 100 92 175 2 1106972336 78997504 17674592
11:17:13 2903 8192000000 9994067760 10361475072 1036432880 11.230 100 82 158 0 1117013168 64132224 16448064
11:18:19 1071 8192000000 10043889088 10419404800 1062472864 11.480 100 95 186 1 1163574512 81898000 19203648
11:19:21 1379 8192000000 10034290048 10409062400 1071050000 11.562 100 89 160 1 1157338704 70893552 15395152
11:20:23 1049 8192000000 10053821424 10434703360 1076046496 11.610 100 95 182 0 1175826400 81671792 18108112
11:21:26 4023 8192000000 10062695872 10442194944 1085642080 11.701 100 89 168 2 1182735920 81210992 15882848
11:22:33 2555 8192000000 10028888768 10412404736 1089001472 11.733 100 86 152 3 1156477488 52812896 14663120
11:23:36 1104 8192000000 10080759440 10466418688 1092405552 11.766 100 96 182 2 1201306224 90602368 18298304
11:24:38 1192 8192000000 10057690368 10444578816 1095595840 11.796 100 98 188 1 1181907296 65983488 20327968
11:25:40 1171 8192000000 10057471808 10448003072 1098550448 11.824 100 92 176 2 1175642352 59146976 17944928
11:26:42 2015 8192000000 10042398160 10432864256 1100869232 11.846 100 73 140 0 1168702400 54468656 13364512
11:27:45 7370 8192000000 10051770800 10450169856 1092603088 11.767 100 70 139 1 1182253792 75919776 13730928
11:28:54 7019 8192000000 10056329136 10459906048 1093372704 11.775 100 63 120 1 1167473376 62065344 12035328
11:30:04 997 8192000000 10008795968 10385674240 1055182080 11.410 99 86 165 2 1128737344 57602256 15953008
11:31:06 1128 8192000000 10035977248 10416644096 1063847024 11.493 100 86 167 0 1158713008 77182320 17683664
11:32:08 1254 8192000000 10022495872 10399514624 1055771600 11.416 99 95 182 2 1144091456 69536368 18783488
11:33:11 6635 8192000000 9991810560 10367639552 1035959296 11.226 98 76 137 1 1107055952 56373984 14722672
11:34:19 1005 8192000000 9999305840 10373238784 1045089888 11.314 100 94 180 4 1119466736 55435920 18940928
11:35:21 1552 8192000000 9983200192 10360336384 1042215040 11.286 100 71 140 3 1105751264 49233920 14302304
11:36:24 2596 8192000000 9968136144 10341220352 1021214576 11.084 99 74 139 0 1096610944 61455488 13940880
11:37:28 5230 8192000000 10007266624 10385510400 1050877520 11.369 100 86 158 1 1134343312 65333776 18132016
11:38:44 4036 8192000000 10011434720 10397454336 1064954096 11.504 100 72 128 4 1123953904 45827952 13171856
11:40:04 4961 8192000000 10053545712 10445275136 1092400432 11.765 100 75 146 1 1172674496 65848336 14425728
11:41:14 1175 8192000000 10080807280 10467459072 1102984272 11.866 100 98 183 5 1202141856 80074976 19082608
11:42:21 3311 8192000000 10104144464 10502586368 1113615680 11.967 100 69 133 1 1218216064 90871216 13729168
11:43:28 27172 8192000000 10116053200 10516910080 1118716544 12.015 100 64 123 0 1228575856 96967872 12891440
11:44:59 805 8192000000 10143898448 10548834304 1132210416 12.142 100 99 191 2 1260657424 108518848 19928160
11:46:03 5219 8192000000 10153345296 10558353408 1148681056 12.297 100 68 126 3 1275857568 113558624 13617888
11:47:14 8577 8192000000 10211568848 10609582080 1164760144 12.448 100 75 140 2 1315158496 135089648 15308704
11:48:26 1206 8192000000 10263543952 10664730624 1173126768 12.526 100 92 169 4 1375617744 185093520 17397456
11:49:32 1136 8192000000 10319991136 10732199936 1179120672 12.582 100 98 197 1 1431704976 229541056 23043248
11:50:36 1061 8192000000 10313566720 10716319744 1185667104 12.643 100 93 177 1 1424145952 217171168 21307680
11:51:40 1420 8192000000 10337537264 10771488768 1187703760 12.662 100 96 190 1 1454857248 245337312 21816176
11:52:45 2317 8192000000 10356904160 10763681792 1184913248 12.636 100 86 161 1 1466922080 263997312 18011520
11:53:49 5882 8192000000 10394751696 10817503232 1192566832 12.707 100 58 114 1 1501506000 295538896 13400272
11:55:03 10776 8192000000 10427748864 10847387648 1189996016 12.683 100 68 136 1 1532167872 325383008 16788848
11:56:23 975 8192000000 10480930848 10906427392 1202827280 12.803 100 95 185 2 1586468784 361070512 22570992
11:57:27 893 8192000000 10471114624 10887340032 1176701008 12.559 99 96 190 1 1578492256 377794528 23996720
11:58:33 797 8192000000 10519375952 10953351168 1201268832 12.788 100 96 181 0 1622997312 400466400 21262080
11:59:36 2205 8192000000 10541905296 10980790272 1207151808 12.843 100 99 191 1 1649109664 417951920 24005936
12:00:40 719 8192000000 10556666176 10974240768 1196196944 12.741 100 82 158 0 1665058176 449436208 19425024
12:01:44 818 8192000000 10575369264 11007340544 1188665904 12.671 100 97 192 0 1681891936 470192304 23033728
12:02:47 1070 8192000000 10584804048 11026763776 1198932784 12.766 100 92 175 1 1689191648 469301552 20957312
12:03:50 1634 8192000000 10582122288 11022135296 1190802192 12.691 100 62 121 3 1689201040 482433808 15965040
12:04:54 4047 8192000000 10583330928 11034542080 1182242704 12.611 100 60 120 1 1694619504 496438944 15937856
12:06:01 12796 8192000000 10622355680 11073667072 1185844688 12.645 100 43 86 1 1731008112 533630752 11532672
12:07:23 16803 8192000000 10607645568 11060776960 1152899184 12.337 99 44 86 1 1695171984 530965936 11306864
12:08:46 22474 8192000000 10524066144 11068067840 1163522720 12.436 100 41 81 2 1672283792 497737472 11023600
12:10:29 25841 8192000000 10264869712 10763935744 1124068784 12.065 99 29 54 0 1390849536 260478304 6302448
12:12:13 47897 8192000000 10091654976 10543140864 1099578784 11.834 96 11 22 0 1136562368 35048304 1935280
12:15:04 63328 8192000000 10109682528 10581057536 1121101024 12.037 98 16 30 0 1204088720 79932128 3055568
12:17:08 51055 8192000000 10101829472 10527903744 1137519600 12.192 100 31 54 2 1182796848 39560880 5716368
12:19:05 87283 8192000000 10090714144 10558722048 1083042320 11.676 95 11 20 0 1136170928 50567264 2561344
12:21:55 107701 8192000000 10063902832 10526789632 1104335408 11.879 97 24 45 0 1176504976 67641552 4528016
12:24:43 16412 8192000000 10070493856 10501783552 1123461136 12.060 99 54 98 3 1161303856 27132048 10710672
12:26:00 129792 8192000000 10079775200 10548170752 1128392000 12.106 99 27 52 1 1172731792 38140064 6199728
12:30:20 45556 8192000000 10134849840 10579304448 1132849584 12.148 99 29 57 0 1222211648 82748336 6613728
12:32:15 50344 8192000000 10093612816 10581770240 1122937120 12.055 99 50 92 3 1204634368 72168160 9529088
12:34:08 54728 8192000000 10076782688 10524467200 1104062768 11.876 97 21 38 1 1148077696 39881392 4133536
12:36:04 38853 8192000000 10071419360 10544914432 1122044432 12.046 99 17 35 0 1166377504 40954960 3378112
12:37:43 85505 8192000000 10159838320 10623467520 1135579360 12.174 99 15 30 0 1195019680 56212960 3227360
12:40:22 82983 8192000000 10084830144 10557997056 1135779424 12.176 99 20 39 0 1206888320 66379408 4729488
12:42:56 49121 8192000000 10130894960 10586001408 1126384992 12.087 98 27 54 0 1210241120 77626240 6229888
12:44:59 67188 8192000000 10114221104 10580819968 1103909088 11.875 96 18 36 0 1177516976 69558832 4049056
12:47:07 47292 8192000000 10150429264 10603163648 1145937248 12.271 99 28 56 0 1226639248 74335744 6366256
12:49:16 50877 8192000000 10140377024 10620575744 1137632704 12.193 99 19 38 0 1229319472 87540128 4146640
12:51:26 59688 8192000000 10157420416 10614661120 1141662880 12.231 99 27 52 0 1222837472 74827616 6346976
12:53:27 44753 8192000000 10144009840 10574569472 1131124176 12.132 98 32 62 1 1189250688 51501392 6625120
12:55:12 133318 8192000000 10108525008 10590588928 1150727136 12.316 100 32 64 0 1297938256 140096496 7114624
12:58:42 83195 8192000000 10082163984 10562097152 1136723280 12.185 99 15 30 0 1192433072 52583024 3126768
13:01:06 71784 8192000000 10083312032 10548633600 1145207248 12.264 100 13 26 0 1175251040 27600464 2443328
13:03:57 70090 8192000000 10062765776 10516803584 1089745936 11.740 96 10 20 0 1132870080 41209488 1914656
13:06:08 94950 8192000000 10066989456 10543276032 1121987344 12.046 99 12 24 0 1179485808 55110880 2387584
13:08:46 26674 8192000000 10089843168 10544517120 1135080592 12.169 100 41 80 0 1189081584 46074480 7926512
13:10:19 30850 8192000000 10085103392 10529693696 1086183472 11.706 96 37 74 0 1136603760 42308992 8111296
13:11:53 33808 8192000000 10119813312 10580824064 1124676432 12.071 100 47 94 1 1220653808 86414320 9563056
13:13:30 19065 8192000000 10096006384 10536701952 1107890624 11.912 98 44 88 0 1173878768 56897776 9090368
13:14:49 81623 8192000000 10098688576 10555232256 1098167264 11.820 97 20 40 0 1190809456 88315184 4327008
13:17:11 58339 8192000000 10100988720 10540453888 1068675072 11.539 94 13 24 0 1128095696 56292144 3128480
13:19:52 63034 8192000000 10071061040 10539585536 1104080000 11.876 98 10 20 0 1168943680 62825424 2038256
13:21:57 137273 8192000000 10071385600 10543501312 1127723008 12.100 100 40 76 1 1199744096 64178672 7842416
13:25:17 53367 8192000000 10065417664 10538074112 1123863600 12.063 99 22 45 1 1168428032 39116880 5447552
13:27:11 55154 8192000000 10085584128 10564820992 1128299840 12.105 99 13 26 0 1196786928 65636272 2850816
13:30:37 80545 8192000000 10101970720 10615062528 1106306320 11.897 98 22 42 0 1222121120 111068368 4746432
13:32:59 61051 8192000000 10097335760 10549342208 1094765392 11.788 97 14 28 1 1160055360 62126944 3163024
13:35:01 41312 8192000000 10099594528 10563981312 1121344960 12.040 99 28 55 0 1169171440 42150416 5676064
13:36:56 39449 8192000000 9053815424 9358540800 192822480 2.299 17 6 11 0 274583120 80198064 1562576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-03-2023 13:38:55 to 12-03-2023 13:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-03-2023 13:39:12 to 12-03-2023 13:39:14.

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          220975
MON$OLDEST_ACTIVE               220976
MON$OLDEST_SNAPSHOT             220976
MON$NEXT_TRANSACTION            220976
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                       2074912
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

12.03.23 13:39:15. 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-03-12 13:39:17 No severe PSQL-related problems occured
12.03.23 13:39:19. End of report.