Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.07.2022 19: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 16066432 kB
MemAvailable 31096744 kB
Buffers 0 kB
Cached 15626624 kB
SwapCached 2520 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-07-10 19:38:21
DTS_END                         2022-07-10 19:38:21.1910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52534
TRN_ID                          244367
ATT_ID                          632
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: 9639.72 20290 1156766
customer order: creation 178.24 375 21389
customer order: refuse 15.45 331 1854
order to supplier: creation 47.08 1229 5650
order to supplier: removal 3.38 98248 405
invoice (draft): creation 48.77 3257 5852
invoice (draft): removal 53.92 818 6470
invoice accept: apply 44.26 17186 5311
invoice accept: cancel 48.54 757 5825
customer reserve: creation 4080.50 170 489660
customer reserve: removal 4807.74 53 576929
realization accept: apply 87.13 180 10455
realization accept: cancel 89.43 103 10732
payment from customer: creation 61.73 116 7407
payment from customer: removal 6.02 76 722
payment to supplier: creation 51.53 135 6184
payment to supplier: removal 3.52 78 422
service: total inventory turnovers 4.72 967 567
service: total monetary turnovers 6.95 31 834
service: aggregate perf. data 0.78 22860 94
service: refresh index statistics 0.03 258833 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-07-2022 19:39:34 to 10-07-2022 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4926
TEST_TIME 2 3605
TEST_TIME 3 3098
TEST_TIME 4 4091
TEST_TIME 5 4399
TEST_TIME 6 4728
TEST_TIME 7 4443
TEST_TIME 8 4664
TEST_TIME 9 4900
TEST_TIME 10 5311
TEST_TIME 11 5730
TEST_TIME 12 6264
TEST_TIME 13 6622
TEST_TIME 14 6646
TEST_TIME 15 7063
TEST_TIME 16 7427
TEST_TIME 17 8028
TEST_TIME 18 8093
TEST_TIME 19 8232
TEST_TIME 20 8420
TEST_TIME 21 8717
TEST_TIME 22 9212
TEST_TIME 23 9124
TEST_TIME 24 9157
TEST_TIME 25 9433
TEST_TIME 26 9513
TEST_TIME 27 9768
TEST_TIME 28 10873
TEST_TIME 29 10826
TEST_TIME 30 10673
TEST_TIME 31 10762
TEST_TIME 32 10608
TEST_TIME 33 10497
TEST_TIME 34 10246
TEST_TIME 35 10248
TEST_TIME 36 10242
TEST_TIME 37 10392
TEST_TIME 38 10170
TEST_TIME 39 10084
TEST_TIME 40 10031
TEST_TIME 41 9906
TEST_TIME 42 9864
TEST_TIME 43 10051
TEST_TIME 44 10044
TEST_TIME 45 9905
TEST_TIME 46 9812
TEST_TIME 47 9888
TEST_TIME 48 9898
TEST_TIME 49 10082
TEST_TIME 50 10086
TEST_TIME 51 10045
TEST_TIME 52 10049
TEST_TIME 53 10058
TEST_TIME 54 10259
TEST_TIME 55 10169
TEST_TIME 56 10137
TEST_TIME 57 10247
TEST_TIME 58 10293
TEST_TIME 59 10284
TEST_TIME 60 10326
TEST_TIME 61 10240
TEST_TIME 62 10110
TEST_TIME 63 10075
TEST_TIME 64 10062
TEST_TIME 65 10004
TEST_TIME 66 9945
TEST_TIME 67 9908
TEST_TIME 68 9951
TEST_TIME 69 9869
TEST_TIME 70 9827
TEST_TIME 71 9833
TEST_TIME 72 9760
TEST_TIME 73 9712
TEST_TIME 74 9625
TEST_TIME 75 9634
TEST_TIME 76 9625
TEST_TIME 77 9607
TEST_TIME 78 9592
TEST_TIME 79 9628
TEST_TIME 80 9693
TEST_TIME 81 9666
TEST_TIME 82 9683
TEST_TIME 83 9718
TEST_TIME 84 9774
TEST_TIME 85 9774
TEST_TIME 86 9790
TEST_TIME 87 9894
TEST_TIME 88 9883
TEST_TIME 89 9802
TEST_TIME 90 9773
TEST_TIME 91 9729
TEST_TIME 92 9686
TEST_TIME 93 9669
TEST_TIME 94 9681
TEST_TIME 95 9635
TEST_TIME 96 9703
TEST_TIME 97 9722
TEST_TIME 98 9699
TEST_TIME 99 9720
TEST_TIME 100 9746
TEST_TIME 101 9838
TEST_TIME 102 9807
TEST_TIME 103 9879
TEST_TIME 104 9886
TEST_TIME 105 9852
TEST_TIME 106 9913
TEST_TIME 107 9893
TEST_TIME 108 9859
TEST_TIME 109 9797
TEST_TIME 110 9781
TEST_TIME 111 9737
TEST_TIME 112 9738
TEST_TIME 113 9708
TEST_TIME 114 9664
TEST_TIME 115 9664
TEST_TIME 116 9660
TEST_TIME 117 9686
TEST_TIME 118 9661
TEST_TIME 119 9651
TEST_TIME 120 9640

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-07-2022 19:39:37 to 10-07-2022 19:39:40.

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 1609607 1609607 0 0.00 0 26892 6 0 0
doc_list_biud 1609607 1609607 0 0.00 0 46441 25 0 0
sp_add_invoice_to_stock 5383 5311 72 1.33 30 185797 17186 0 72
sp_cancel_adding_invoice 5825 5825 0 0.00 2 49575 757 0 0
sp_cancel_client_order 1854 1854 0 0.00 3 12907 332 0 0
sp_cancel_customer_reserve 576929 576929 0 0.00 0 46447 53 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 3880 76 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 2 4291 79 0 0
sp_cancel_supplier_invoice 6478 6470 8 0.12 2 21462 818 0 8
sp_cancel_supplier_order 405 405 0 0.00 5 853683 98249 0 0
sp_cancel_write_off 10732 10732 0 0.00 1 12774 103 0 0
sp_client_order 21389 21389 0 0.00 3 23164 375 0 0
sp_customer_reserve 489660 489660 0 0.00 0 95641 171 0 0
sp_fill_shopping_cart 32887 32887 0 0.00 1 11660 36 0 0
sp_fill_shopping_cart_clo_res 489659 489659 0 0.00 0 15259 15 0 0
sp_get_clo_for_invoice 5310 5310 0 0.00 0 23963 304 0 0
sp_kill_cost_storno 17701 17701 0 0.00 0 16759 55 0 0
sp_kill_qstorno_ret_qs2qd 1171465 1171465 0 0.00 0 46437 30 0 0
sp_kill_qty_storno 617980 617980 0 0.00 0 46440 64 0 0
sp_lock_selected_doc 21662 21662 0 0.00 0 8330 6 0 0
sp_make_cost_storno 29356 29356 0 0.00 0 18685 59 0 0
sp_multiply_rows_for_pdistr 29356 29356 0 0.00 0 8693 26 0 0
sp_multiply_rows_for_qdistr 21389 21389 0 0.00 0 23147 164 0 0
sp_pay_from_customer 7407 7407 0 0.00 4 17674 117 0 0
sp_pay_to_supplier 6184 6184 0 0.00 5 19947 136 0 0
sp_payment_common 13591 13591 0 0.00 1 5067 23 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 12901 302 0 0
sp_qd_handle_on_invoice_upd_sts 11135 11135 0 0.00 0 21052 312 0 0
sp_qd_handle_on_reserve_upd_sts 10455 10455 0 0.00 0 15679 61 0 0
sp_reserve_write_off 10455 10455 0 0.00 6 16610 181 0 0
sp_supplier_invoice 5852 5852 0 0.00 5 84578 3258 0 0
sp_supplier_order 5650 5650 0 0.00 4 23707 1229 0 0
srv_aggregate_perf_data 94 94 0 0.00 2267 347129 22860 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1173 121079 43002 0 0
srv_find_qd_qs_mism 1106428 1106428 0 0.00 0 26892 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 28 21788 968 0 0
srv_make_money_saldo 834 834 0 0.00 0 6624 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 139513 397364 258833 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5311 5311 0 0.00 0 13435 153 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 4495 80 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 4581 17 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 5421 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 559 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 5448 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 2357 20 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 29248 1742 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 7944 21 0 0
t$perf-norm:sp_client_order 21389 21389 0 0.00 0 16005 18 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 7288 12 0 0
t$perf-norm:sp_pay_from_customer 7407 7407 0 0.00 0 13669 16 0 0
t$perf-norm:sp_pay_to_supplier 6184 6184 0 0.00 0 6311 12 0 0
t$perf-norm:sp_reserve_write_off 10455 10455 0 0.00 0 15881 12 0 0
t$perf-norm:sp_supplier_invoice 5852 5852 0 0.00 0 6084 17 0 0
t$perf-norm:sp_supplier_order 5650 5650 0 0.00 0 10802 20 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 541 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 26 1 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 1116 16 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 4079 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 1 1 0 0
v_add_invoice_to_stock 10764 10620 144 1.33 0 10726 20 0 144
v_all_customers 39008 39008 0 0.00 0 16 0 0 0
v_all_suppliers 32082 32082 0 0.00 0 42 1 0 0
v_all_wares 42778 42778 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 3823 12 0 0
v_cancel_client_order 3708 3708 0 0.00 0 2632 5 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 2436 8 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2185 6 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 4284 9 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 1902 14 0 16
v_cancel_supplier_order 810 810 0 0.00 0 155 3 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2424 20 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 4787 3 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 2135 11 0 0
v_max_id_ord_sup 5848 5848 0 0.00 0 3671 5 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 944 3 0 0
v_max_non_paid_realizn 3980 3980 0 0.00 0 3486 5 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 9536 8 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 2299 4 0 0
v_min_id_ord_sup 5848 5848 0 0.00 0 5468 11 0 0
v_min_non_paid_invoice 6184 6184 0 0.00 0 3638 15 0 0
v_min_non_paid_realizn 7407 7407 0 0.00 0 4973 17 0 0
v_our_firm 10624 10624 0 0.00 0 21 1 0 0
v_reserve_write_off 20910 20910 0 0.00 0 10645 7 0 0
x_lock_depdocs_on_canc_invoice 11899 11899 0 0.00 0 13946 113 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 7016 350 0 0
x_make_qstorno_1000_1200 5650 5650 0 0.00 1 23685 1159 0 0
x_make_qstorno_1200_2000 5852 5852 0 0.00 2 84552 3127 0 0
x_make_qstorno_2100_3300 489660 489660 0 0.00 0 95641 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-07-2022 19:39:41 to 10-07-2022 19:39:45.

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
17:08:25 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:25 28747 8192000000 9912644048 10226298880 995281120 10.833 100 89 178 0 1024606016 6323792 23001104
17:11:05 20014 8192000000 9955366528 10283081728 1041557088 11.280 100 84 168 0 1071847936 9040112 21250736
17:12:34 10661 8192000000 9988157920 10324054016 1074509120 11.595 100 82 165 0 1105373856 10407104 20457632
17:13:56 9073 8192000000 10028277984 10378219520 1120292800 12.030 100 74 148 0 1148974944 10983040 17699104
17:15:12 3539 8192000000 10088098320 10451345408 1176681776 12.559 100 71 142 0 1204086432 10723488 16681168
17:16:17 4386 8192000000 10130128192 10501750784 1217672416 12.940 100 76 149 0 1246083216 12414224 15996576
17:17:27 9293 8192000000 10158315168 10536665088 1249321168 13.232 100 80 160 0 1281177808 14352336 17504304
17:18:39 1555 8192000000 10182845664 10569330688 1280358176 13.516 100 69 137 1 1305905520 10518784 15028560
17:19:43 741 8192000000 10208801312 10595602432 1295353056 13.653 100 84 164 0 1326000304 11186464 19460784
17:20:44 29528 8192000000 10186356176 10598883328 1299495872 13.691 100 33 66 0 1312907856 5721984 7690000
17:22:19 4512 8192000000 10199478256 10609340416 1314513184 13.827 100 25 48 0 1324697920 5474544 4710192
17:23:28 13622 8192000000 10208061472 10625662976 1324112736 13.914 100 27 53 0 1336062608 6399616 5550256
17:24:43 12071 8192000000 10219037312 10633281536 1333487024 13.999 100 33 66 0 1347032480 5022800 8522656
17:25:56 20057 8192000000 10220537632 10644082688 1332421808 13.989 100 26 52 1 1348540608 10125184 5993616
17:27:22 7565 8192000000 10235322288 10657456128 1340797776 14.065 100 16 32 0 1350171168 5622400 3750992
17:28:31 7739 8192000000 10199961872 10621222912 1295833056 13.657 98 21 42 1 1306970832 6246736 4891040
17:29:39 9966 8192000000 10161694768 10565627904 1224679264 13.005 95 22 45 2 1235539392 5297328 5562800
17:30:51 14688 8192000000 10140303984 10543300608 1162611568 12.428 92 18 36 0 1172457840 5651440 4194832
17:32:09 4752 8192000000 10116188800 10513997824 1215086928 12.916 98 24 48 0 1225978128 5252160 5639040
17:33:14 9771 8192000000 10153613280 10552684544 1251282448 13.250 99 26 52 0 1262616864 4729360 6605056
17:34:24 13987 8192000000 10184920976 10586775552 1279975760 13.513 100 21 42 0 1289798208 5145200 4677248
17:35:39 10398 8192000000 10171944048 10586882048 1286158016 13.569 100 24 48 0 1297910368 6662368 5089984
17:36:51 7371 8192000000 10195099856 10611695616 1304417952 13.735 100 23 46 0 1315586640 5977120 5191568
17:38:00 7335 8192000000 10222142528 10629914624 1318645360 13.864 100 22 42 2 1328025440 4551856 4828224
17:39:16 16707 8192000000 10250408976 10662801408 1346085808 14.112 100 38 74 0 1362598768 8356256 8156704
17:40:37 121079 8192000000 10275037360 10703241216 1368750800 14.316 100 47 94 1 1392535728 12148464 11636464
17:43:50 34632 8192000000 10280155248 10713894912 1367445632 14.304 99 26 50 0 1390626848 16917088 6264128
17:45:30 19465 8192000000 10319445856 10735534080 1390746528 14.513 100 56 111 1 1420648944 17109920 12792496
17:46:53 8725 8192000000 10326884800 10747252736 1400119856 14.596 100 68 136 0 1440396816 23836640 16440320
17:48:04 13539 8192000000 10329692192 10759008256 1396467264 14.564 99 51 102 0 1437002480 26980080 13555136
17:49:29 4388 8192000000 10356597680 10776190976 1419617840 14.769 100 72 140 0 1458765104 22759504 16387760
17:50:42 2060 8192000000 10364638960 10782511104 1422528688 14.795 100 75 150 0 1468665760 28080960 18056112
17:51:47 5193 8192000000 10347861088 10770894848 1415767920 14.735 100 67 131 0 1455934336 24302320 15864096
17:53:03 1170 8192000000 10339156368 10758926336 1399751088 14.593 99 69 133 0 1445804656 29528832 16524736
17:54:13 12419 8192000000 10335837440 10764988416 1389598128 14.502 99 61 122 0 1440522576 34927344 15997104
17:55:33 3214 8192000000 10341466704 10765090816 1403685200 14.628 100 56 112 1 1451115104 33220432 14209472
17:56:41 20693 8192000000 10325650496 10753024000 1367100672 14.301 98 32 64 0 1408545952 32885584 8559696
17:58:04 5802 8192000000 10316011280 10738995200 1373503440 14.358 100 39 79 0 1425623360 41695888 10424032
17:59:15 4397 8192000000 10335965936 10749124608 1372539984 14.350 100 71 141 1 1434290496 44202816 17547696
18:00:22 14746 8192000000 10312345296 10744389632 1382294608 14.437 100 40 80 1 1422702016 30371408 10036000
18:01:49 10694 8192000000 10322017872 10751451136 1372712592 14.351 99 31 59 0 1418182048 37486624 7982832
18:03:09 26562 8192000000 10322570128 10755383296 1368658912 14.315 99 27 54 0 1413681136 37502320 7519904
18:05:03 38858 8192000000 10299047456 10744950784 1345661024 14.108 99 19 38 0 1401218384 49898544 5658816
18:06:43 37954 8192000000 10260239136 10706698240 1339174864 14.050 99 6 12 0 1356009120 15391120 1443136
18:08:53 25481 8192000000 10298245200 10731769856 1373286560 14.356 100 53 100 2 1404173568 18901664 11985344
18:10:20 59692 8192000000 10261950496 10697961472 1310653104 13.792 96 29 58 1 1325668000 8502208 6512688
18:12:21 37197 8192000000 10271398320 10709778432 1352117520 14.167 99 45 88 0 1370622752 6966496 11538736
18:14:09 11418 8192000000 10300170240 10727354368 1374170048 14.364 100 59 113 0 1398358896 9644688 14544160
18:15:27 97997 8192000000 10289564592 10740436992 1369160080 14.320 99 11 23 0 1385934096 14169312 2604704
18:18:10 47945 8192000000 10318626576 10764300288 1396177200 14.561 100 24 48 0 1424520448 22387584 5955664
18:20:02 59415 8192000000 10326637648 10780139520 1406160736 14.650 100 16 32 0 1437085776 26924560 4000480
18:22:07 27200 8192000000 10298680784 10764001280 1400082176 14.596 100 11 23 0 1414408128 12038448 2287504
18:23:40 69251 8192000000 10326522320 10782900224 1413014848 14.711 100 16 32 0 1435035568 17844720 4176000
18:26:05 50787 8192000000 10321550480 10784903168 1406668560 14.654 100 13 27 0 1431179072 21122416 3388096
18:28:15 51304 8192000000 10315593440 10784071680 1405813232 14.647 100 15 30 0 1422416048 13074480 3528336
18:30:07 30396 8192000000 10341108624 10785730560 1413831536 14.718 100 46 85 1 1434840976 10556688 10452752
18:31:39 60675 8192000000 10321166128 10791473152 1382578288 14.440 98 13 26 0 1412562640 26685424 3298928
18:33:46 42062 8192000000 10328416448 10768510976 1384143056 14.454 98 39 78 0 1405985536 12775792 9066688
18:35:29 81068 8192000000 10309504432 10776313856 1379912784 14.416 98 9 18 1 1406020896 23584352 2523760
18:37:53 15945 8192000000 10321412016 10769051648 1405984432 14.648 100 28 56 0 1419990800 8188640 5817728
18:39:09 87190 8192000000 10288493664 10741497856 1353357872 14.178 97 14 26 2 1363307264 7138112 2811280
18:41:56 73149 8192000000 10271115184 10740121600 1337898032 14.038 96 14 28 0 1355240176 13934832 3407312
18:44:13 55880 8192000000 10295284064 10748997632 1369826448 14.325 98 38 76 0 1389538720 11079392 8632880
18:46:13 32973 8192000000 10261673680 10719391744 1324852112 13.921 96 4 8 0 1334619504 8945872 821520
18:47:47 66018 8192000000 10249850960 10707025920 1355399088 14.196 100 20 40 0 1373461280 13368288 4693904
18:49:55 44030 8192000000 10234629024 10685648896 1309394496 13.781 97 13 27 0 1317677216 5263488 3019232
18:51:51 94917 8192000000 10248640432 10710958080 1352317168 14.168 100 16 32 0 1367275152 11473760 3484224
18:54:32 16860 8192000000 10251795792 10695794688 1349500672 14.143 100 26 44 4 1358874416 4921056 4452688
18:55:51 22165 8192000000 10238221664 10679021568 1317512528 13.854 99 34 68 1 1333418496 8511136 7394832
18:57:14 40499 8192000000 10242035072 10692517888 1295670992 13.656 97 31 61 0 1319671984 16953632 7047360
18:58:58 26513 8192000000 10252578880 10683379712 1323639552 13.910 99 40 80 0 1341532160 8310272 9582336
19:00:30 18446 8192000000 10258558896 10693468160 1319824768 13.875 98 50 100 0 1344489776 13899904 10765104
19:01:53 50934 8192000000 10263749248 10703491072 1336802912 14.029 99 31 62 0 1362860592 19063024 6994656
19:03:45 67832 8192000000 10274877088 10719223808 1359061392 14.229 100 26 52 0 1379229168 14338608 5829168
19:05:53 52800 8192000000 10281876944 10723831808 1350811360 14.155 99 32 59 1 1369934496 12520784 6602352
19:07:49 81940 8192000000 10274624208 10726289408 1362434112 14.259 99 25 50 0 1378764992 11304016 5026864
19:10:12 66881 8192000000 10267209232 10725007360 1373247072 14.356 99 7 14 0 1382539072 7998960 1293040
19:12:22 51823 8192000000 10287206976 10747351040 1369015680 14.318 99 8 16 0 1378084272 7585040 1483552
19:14:14 76681 8192000000 10281084176 10756100096 1381819632 14.433 100 24 48 1 1400579376 13130368 5629376
19:16:31 31931 8192000000 10293515024 10745958400 1360193008 14.239 98 26 51 0 1377197472 11216928 5787536
19:18:03 52953 8192000000 10307683312 10774208512 1403244256 14.624 100 24 48 0 1427872544 18759632 5868656
19:20:24 68886 8192000000 10296546720 10765279232 1361770176 14.253 98 13 26 0 1379207408 14485792 2951440
19:22:35 59541 8192000000 10302182576 10755616768 1366145952 14.293 98 25 51 0 1380912832 9278736 5488144
19:24:35 80620 8192000000 10288052240 10743197696 1348546624 14.134 97 13 26 1 1363801936 12001984 3253328
19:26:56 109038 8192000000 10278284048 10732957696 1349706896 14.145 97 29 58 0 1362584752 6513568 6364288
19:29:48 55182 8192000000 10265026064 10726887424 1345519456 14.107 97 16 32 0 1358802400 9701712 3581232
19:31:45 41595 8192000000 10266842768 10729140224 1368472944 14.313 100 11 22 0 1383231504 12415120 2343440
19:34:01 61098 8192000000 10251542000 10713980928 1344814240 14.101 98 16 32 0 1359499072 11237504 3447328
19:36:10 33645 8192000000 10264176384 10701705216 1282643936 13.537 94 24 48 0 1297737920 9421328 5672656
19:37:46 45862 8192000000 9118344368 9365299200 287831472 3.394 21 6 12 0 295723008 6423456 1468080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-07-2022 19:39:47 to 10-07-2022 19:40:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-07-2022 19:40:03 to 10-07-2022 19:40:05.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246111
MON$OLDEST_ACTIVE               246112
MON$OLDEST_SNAPSHOT             246112
MON$NEXT_TRANSACTION            246112
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-03 16:41:55.3470
MON$PAGES                       2033696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

10.07.22 19:40:06. There are 3 logs in /var/tmp/logs-oltp30 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-07-10 19:40:09 No severe PSQL-related problems occured
10.07.22 19:40:10. End of report.