Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.04.2022 07: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 15923168 kB
MemAvailable 31462272 kB
Buffers 0 kB
Cached 15744616 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-18 07:37:13
DTS_END                         2022-04-18 07:37:13.1930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38968
TRN_ID                          215989
ATT_ID                          638
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: 8626.73 30584 1035207
customer order: creation 162.24 481 19469
customer order: refuse 13.90 507 1668
order to supplier: creation 43.67 1500 5240
order to supplier: removal 3.16 179768 379
invoice (draft): creation 43.93 3922 5271
invoice (draft): removal 47.79 1061 5735
invoice accept: apply 39.84 28298 4781
invoice accept: cancel 43.49 1556 5219
customer reserve: creation 3715.73 265 445887
customer reserve: removal 4227.77 104 507333
realization accept: apply 80.32 304 9638
realization accept: cancel 79.46 172 9535
payment from customer: creation 57.41 185 6889
payment from customer: removal 5.33 140 640
payment to supplier: creation 46.85 218 5622
payment to supplier: removal 3.36 135 403
service: total inventory turnovers 4.04 4678 485
service: total monetary turnovers 7.43 409 892
service: aggregate perf. data 0.98 16451 117
service: refresh index statistics 0.03 371526 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-04-2022 07:38:30 to 18-04-2022 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7525
TEST_TIME 2 6537
TEST_TIME 3 5438
TEST_TIME 4 5432
TEST_TIME 5 5207
TEST_TIME 6 5328
TEST_TIME 7 5755
TEST_TIME 8 5683
TEST_TIME 9 5658
TEST_TIME 10 6136
TEST_TIME 11 6144
TEST_TIME 12 6252
TEST_TIME 13 6372
TEST_TIME 14 6696
TEST_TIME 15 6930
TEST_TIME 16 7117
TEST_TIME 17 7137
TEST_TIME 18 7290
TEST_TIME 19 7644
TEST_TIME 20 7698
TEST_TIME 21 7741
TEST_TIME 22 7869
TEST_TIME 23 7940
TEST_TIME 24 8062
TEST_TIME 25 8064
TEST_TIME 26 8274
TEST_TIME 27 8148
TEST_TIME 28 8159
TEST_TIME 29 8217
TEST_TIME 30 8933
TEST_TIME 31 9161
TEST_TIME 32 9262
TEST_TIME 33 9411
TEST_TIME 34 9578
TEST_TIME 35 9602
TEST_TIME 36 9831
TEST_TIME 37 10045
TEST_TIME 38 10194
TEST_TIME 39 10486
TEST_TIME 40 10571
TEST_TIME 41 10721
TEST_TIME 42 10734
TEST_TIME 43 10640
TEST_TIME 44 10487
TEST_TIME 45 10383
TEST_TIME 46 10244
TEST_TIME 47 10102
TEST_TIME 48 10054
TEST_TIME 49 9974
TEST_TIME 50 9926
TEST_TIME 51 9808
TEST_TIME 52 9766
TEST_TIME 53 9699
TEST_TIME 54 9660
TEST_TIME 55 9557
TEST_TIME 56 9481
TEST_TIME 57 9389
TEST_TIME 58 9357
TEST_TIME 59 9313
TEST_TIME 60 9210
TEST_TIME 61 9189
TEST_TIME 62 9175
TEST_TIME 63 9106
TEST_TIME 64 9081
TEST_TIME 65 9102
TEST_TIME 66 9018
TEST_TIME 67 8948
TEST_TIME 68 8890
TEST_TIME 69 8839
TEST_TIME 70 8832
TEST_TIME 71 8748
TEST_TIME 72 8755
TEST_TIME 73 8825
TEST_TIME 74 8831
TEST_TIME 75 8843
TEST_TIME 76 8832
TEST_TIME 77 8810
TEST_TIME 78 8838
TEST_TIME 79 8847
TEST_TIME 80 8871
TEST_TIME 81 8843
TEST_TIME 82 8898
TEST_TIME 83 8875
TEST_TIME 84 9067
TEST_TIME 85 9103
TEST_TIME 86 9095
TEST_TIME 87 9018
TEST_TIME 88 8966
TEST_TIME 89 8901
TEST_TIME 90 8949
TEST_TIME 91 8883
TEST_TIME 92 8832
TEST_TIME 93 8817
TEST_TIME 94 8789
TEST_TIME 95 8722
TEST_TIME 96 8709
TEST_TIME 97 8685
TEST_TIME 98 8660
TEST_TIME 99 8747
TEST_TIME 100 8716
TEST_TIME 101 8725
TEST_TIME 102 8718
TEST_TIME 103 8739
TEST_TIME 104 8722
TEST_TIME 105 8782
TEST_TIME 106 8757
TEST_TIME 107 8800
TEST_TIME 108 8850
TEST_TIME 109 8921
TEST_TIME 110 8877
TEST_TIME 111 8809
TEST_TIME 112 8807
TEST_TIME 113 8756
TEST_TIME 114 8780
TEST_TIME 115 8753
TEST_TIME 116 8724
TEST_TIME 117 8700
TEST_TIME 118 8660
TEST_TIME 119 8682
TEST_TIME 120 8651

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-04-2022 07:38:34 to 18-04-2022 07:38:37.

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 1444754 1444754 0 0.00 0 21510 15 0 0
doc_list_biud 1444743 1444743 0 0.00 0 30825 43 0 0
sp_add_invoice_to_stock 4905 4781 124 2.52 24 183309 28299 0 124
sp_cancel_adding_invoice 5219 5219 0 0.00 6 98555 1557 0 0
sp_cancel_client_order 1668 1668 0 0.00 7 21025 507 0 0
sp_cancel_customer_reserve 507333 507333 0 0.00 1 24720 105 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 4745 140 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 6053 136 0 0
sp_cancel_supplier_invoice 5746 5735 11 0.19 4 21530 1062 0 11
sp_cancel_supplier_order 379 379 0 0.00 9 1667959 179769 0 0
sp_cancel_write_off 9535 9535 0 0.00 2 12725 172 0 0
sp_client_order 19469 19469 0 0.00 6 35072 482 0 0
sp_customer_reserve 445887 445887 0 0.00 1 100090 266 0 0
sp_fill_shopping_cart 29970 29970 0 0.00 2 17896 97 0 0
sp_fill_shopping_cart_clo_res 445858 445858 0 0.00 0 16619 20 0 0
sp_get_clo_for_invoice 4741 4741 0 0.00 0 19077 282 0 0
sp_kill_cost_storno 15797 15797 0 0.00 0 21501 91 0 0
sp_kill_qstorno_ret_qs2qd 1030315 1030315 0 0.00 0 26391 45 0 0
sp_kill_qty_storno 544245 544245 0 0.00 0 30825 106 0 0
sp_lock_selected_doc 19893 19893 0 0.00 0 7609 8 0 0
sp_make_cost_storno 26889 26889 0 0.00 0 9207 88 0 0
sp_multiply_rows_for_pdistr 26888 26888 0 0.00 0 7669 44 0 0
sp_multiply_rows_for_qdistr 19469 19469 0 0.00 0 25380 152 0 0
sp_pay_from_customer 6889 6889 0 0.00 5 8472 185 0 0
sp_pay_to_supplier 5622 5622 0 0.00 7 7694 219 0 0
sp_payment_common 12510 12510 0 0.00 2 7644 49 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 1 21006 432 0 0
sp_qd_handle_on_invoice_upd_sts 9958 9958 0 0.00 0 30823 634 0 0
sp_qd_handle_on_reserve_upd_sts 9637 9637 0 0.00 0 11770 67 0 0
sp_reserve_write_off 9638 9638 0 0.00 9 13528 304 0 0
sp_supplier_invoice 5271 5271 0 0.00 11 108945 3923 0 0
sp_supplier_order 5240 5240 0 0.00 8 41537 1500 0 0
srv_aggregate_perf_data 117 117 0 0.00 2508 233916 16452 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 872 148801 34394 0 0
srv_find_qd_qs_mism 987733 987733 0 0.00 0 12975 11 0 0
srv_make_invnt_saldo 485 485 0 0.00 66 77802 4678 0 0
srv_make_money_saldo 892 892 0 0.00 1 23122 410 0 0
srv_recalc_idx_stat 4 4 0 0.00 198488 475380 371527 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4784 4784 0 0.00 1 12169 1152 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 8854 626 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 4234 30 0 0
t$perf-norm:sp_cancel_customer_reserve 614 614 0 0.00 0 959 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 7921 38 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 108 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1465 46 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 114897 8752 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 4274 30 0 0
t$perf-norm:sp_client_order 19470 19470 0 0.00 0 9991 43 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 4779 24 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 6162 21 0 0
t$perf-norm:sp_pay_to_supplier 5622 5622 0 0.00 0 6439 24 0 0
t$perf-norm:sp_reserve_write_off 9638 9638 0 0.00 0 8760 27 0 0
t$perf-norm:sp_supplier_invoice 5271 5271 0 0.00 0 4228 37 0 0
t$perf-norm:sp_supplier_order 5240 5240 0 0.00 0 1743 36 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 3500 58 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 84 5 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 1163 16 0 0
t$perf-norm:srv_make_money_saldo 892 892 0 0.00 0 795 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 156 44 0 0
v_add_invoice_to_stock 9720 9472 248 2.55 0 3364 27 0 248
v_all_customers 35782 35782 0 0.00 0 78 4 0 0
v_all_suppliers 29396 29396 0 0.00 0 106 4 0 0
v_all_wares 38938 38938 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 1233 18 0 0
v_cancel_client_order 3336 3336 0 0.00 0 1447 10 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 863 12 0 0
v_cancel_customer_reserve 1228 1228 0 0.00 0 1057 11 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1417 15 0 0
v_cancel_supplier_invoice 844 822 22 2.60 0 2720 30 0 22
v_cancel_supplier_order 758 758 0 0.00 0 1052 13 0 0
v_cancel_write_off 1964 1964 0 0.00 0 6505 33 0 0
v_max_id_clo_ord 5237 5237 0 0.00 0 244 5 0 0
v_max_id_clo_res 947 947 0 0.00 0 418 9 0 0
v_max_id_ord_sup 5263 5263 0 0.00 0 5524 7 0 0
v_max_non_paid_invoice 1423 1423 0 0.00 0 440 8 0 0
v_max_non_paid_realizn 3524 3524 0 0.00 0 872 7 0 0
v_min_id_clo_ord 5237 5237 0 0.00 0 1236 9 0 0
v_min_id_clo_res 947 947 0 0.00 0 5346 14 0 0
v_min_id_ord_sup 5263 5263 0 0.00 0 10147 15 0 0
v_min_non_paid_invoice 5620 5620 0 0.00 0 7626 19 0 0
v_min_non_paid_realizn 6888 6888 0 0.00 0 5544 18 0 0
v_our_firm 9886 9886 0 0.00 0 60 5 0 0
v_reserve_write_off 19274 19274 0 0.00 0 3263 10 0 0
x_lock_depdocs_on_canc_invoice 10543 10543 0 0.00 0 29047 137 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 4490 432 0 0
x_make_qstorno_1000_1200 5240 5240 0 0.00 2 41521 1315 0 0
x_make_qstorno_1200_2000 5271 5271 0 0.00 4 102897 3645 0 0
x_make_qstorno_2100_3300 445887 445887 0 0.00 0 100087 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 07:38:38 to 18-04-2022 07:38:42.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:17 21325 8192000000 9723922240 10025984000 804411104 8.941 100 94 188 1 833918112 7183680 22323328
05:09:50 20068 8192000000 9769224064 10080976896 845870768 9.359 100 88 176 0 879173520 12343664 20959088
05:11:16 22615 8192000000 9803959952 10126028800 872275456 9.623 100 80 160 1 910308656 19536784 18496416
05:12:41 10968 8192000000 9852764592 10187710464 910883136 10.006 100 76 152 0 965676352 37956368 16836848
05:13:55 6512 8192000000 9914902896 10257162240 948367232 10.375 100 85 165 1 1031520224 65197472 17955520
05:15:06 3849 8192000000 9947679344 10302435328 991157920 10.793 100 79 159 0 1070957696 62910448 16889328
05:16:14 2824 8192000000 9991326480 10354360320 1023896304 11.110 100 85 162 0 1110842736 70721920 16224512
05:17:20 1029 8192000000 10026087712 10396229632 1045415504 11.317 100 99 191 1 1144510384 79264656 19830224
05:18:22 883 8192000000 10018285776 10393149440 1059997520 11.456 100 94 179 1 1136845984 58367280 18481184
05:19:25 1031 8192000000 10042055744 10421383168 1068674384 11.539 100 98 187 0 1165115664 77431312 19009968
05:20:29 824 8192000000 10074593920 10458398720 1075614912 11.606 100 94 180 0 1192779680 97927408 19237360
05:21:33 1440 8192000000 10048824304 10429669376 1080707664 11.654 100 96 183 1 1169561760 69724784 19129312
05:22:36 1109 8192000000 10057402496 10442752000 1087266816 11.717 100 99 188 2 1179584800 72156272 20161712
05:23:39 3191 8192000000 10071122720 10463318016 1094607920 11.786 100 91 179 1 1199914448 87505312 17801216
05:24:43 1417 8192000000 10069578160 10458697728 1093697888 11.778 100 97 185 0 1191770320 79660368 18412064
05:25:46 1115 8192000000 10092144320 10476945408 1096217776 11.802 100 98 190 2 1208067600 92936352 18913472
05:26:49 1388 8192000000 10075687984 10465742848 1099906864 11.837 100 97 188 1 1195267584 76492432 18868288
05:27:51 944 8192000000 10049511776 10445377536 1095916048 11.799 100 84 165 1 1171263024 58372464 16974512
05:28:53 1495 8192000000 10041361424 10437529600 1100498352 11.842 100 75 133 4 1164247184 49541680 14207152
05:29:56 1085 8192000000 10074796288 10468917248 1091400512 11.756 100 95 180 0 1201484992 91070304 19014176
05:30:58 1320 8192000000 10086239648 10472853504 1090666352 11.749 100 96 176 0 1211502176 103046464 17789360
05:32:01 1322 8192000000 10068041632 10455810048 1088628672 11.730 100 96 185 1 1191652784 83717152 19306960
05:33:03 1360 8192000000 9998545904 10378305536 1032163888 11.189 97 90 160 0 1120821360 71358112 17299360
05:34:06 931 8192000000 10030903040 10410315776 1059901040 11.456 100 97 173 4 1146363888 67538096 18924752
05:35:08 1839 8192000000 10010490192 10387447808 1046875008 11.331 100 93 175 2 1135087328 67763424 20448896
05:36:11 1231 8192000000 9995076448 10375319552 1037838704 11.244 100 85 162 2 1116117776 60598144 17680928
05:37:14 5561 8192000000 9993284016 10376302592 1047719440 11.339 100 84 162 0 1118485904 53632816 17133648
05:38:31 6362 8192000000 10015787584 10401337344 1057293632 11.431 100 60 110 0 1135496720 67655280 10547808
05:39:43 14082 8192000000 10023726048 10410188800 1073126368 11.582 100 75 146 0 1157612112 69348592 15137152
05:41:03 882 8192000000 10061848976 10446872576 1094425360 11.785 100 85 160 2 1182636800 71898752 16312688
05:42:06 3844 8192000000 10092718544 10485440512 1095759712 11.797 100 64 125 1 1214475024 104581040 14134272
05:43:12 1027 8192000000 10138040736 10518241280 1099429984 11.832 100 94 180 0 1250654176 133711936 17512256
05:44:20 1118 8192000000 10144240320 10529120256 1099397136 11.832 99 93 179 3 1259590624 141756912 18436576
05:45:27 1119 8192000000 10186771056 10578882560 1115753616 11.987 100 77 146 2 1302769312 171827664 15188032
05:46:35 2699 8192000000 10217152368 10609881088 1145087040 12.263 100 83 160 0 1328560176 166505200 16967936
05:47:43 2098 8192000000 10224377328 10621861888 1156089776 12.367 100 80 155 0 1333514768 160715248 16709744
05:48:50 2049 8192000000 10269510880 10678816768 1167246688 12.471 100 87 167 1 1383635088 198020688 18367712
05:49:55 2695 8192000000 10310081136 10711994368 1171984816 12.515 100 93 181 0 1426327456 233738000 20604640
05:51:02 1991 8192000000 10328805920 10743267328 1173670992 12.531 100 95 183 0 1438480032 243181952 21627088
05:52:07 867 8192000000 10326517696 10752462848 1186782480 12.653 100 95 185 0 1441748016 232994432 21971104
05:53:11 2589 8192000000 10332842768 10743930880 1189784512 12.681 100 92 177 0 1444021232 233968544 20268176
05:54:17 1013 8192000000 10368782368 10776100864 1180104800 12.591 100 94 179 0 1477367568 277148176 20114592
05:55:21 1256 8192000000 10414936640 10847645696 1196576912 12.745 100 96 182 2 1524509616 307383472 20549232
05:56:25 1272 8192000000 10429170896 10859585536 1192546032 12.707 100 99 194 1 1536305616 321101536 22658048
05:57:32 1284 8192000000 10448576576 10862325760 1186037344 12.646 100 97 188 1 1554969952 347351280 21581328
05:58:37 1550 8192000000 10463791552 10879037440 1189951856 12.683 100 94 181 0 1571128112 359697904 21478352
05:59:41 932 8192000000 10467807936 10907668480 1199090928 12.768 100 97 192 4 1572985856 351101408 22793520
06:00:46 1139 8192000000 10476444368 10904559616 1188610304 12.670 100 96 186 0 1581900848 370722272 22568272
06:01:49 1763 8192000000 10523743616 10937044992 1194421200 12.724 100 87 170 0 1628787296 413794208 20571888
06:02:58 4540 8192000000 10526345808 10982559744 1200994720 12.786 100 67 132 0 1627750592 409884896 16870976
06:04:16 10878 8192000000 10546297472 10995449856 1198055456 12.758 100 56 112 0 1664233664 452228368 13949840
06:05:34 3578 8192000000 10563590224 10980896768 1181333136 12.603 100 89 155 1 1661711376 461573120 18805120
06:06:50 5082 8192000000 10577302768 11043815424 1183602688 12.624 100 76 147 1 1676929536 474668032 18658816
06:08:01 27291 8192000000 10433077040 10910572544 1176235744 12.555 99 34 68 0 1526847104 341726976 8884384
06:09:37 10400 8192000000 10388666688 10867142656 1157349376 12.378 99 35 68 1 1485067840 318956672 8761792
06:10:50 14964 8192000000 10347879056 10826743808 1147447632 12.286 100 30 59 0 1481330752 326458368 7424752
06:12:10 21299 8192000000 10358950608 10814124032 1130838400 12.129 98 55 101 2 1429333952 286006032 12489520
06:13:46 3894 8192000000 10313082704 10767101952 1147302768 12.284 99 54 104 1 1407873952 248216624 12354560
06:14:54 15372 8192000000 10305938288 10763001856 1157810688 12.383 100 37 72 1 1420584016 254177424 8595904
06:16:10 44997 8192000000 10168766432 10661244928 1147707744 12.288 100 23 44 4 1338147392 184660512 5779136
06:17:57 54858 8192000000 10121927712 10612400128 1116266688 11.992 97 12 24 0 1240089024 120380992 3441344
06:19:54 41486 8192000000 10081728432 10547752960 1124776320 12.072 99 35 70 2 1183904416 51874160 7253936
06:22:03 18253 8192000000 10083996976 10517524480 1141534224 12.230 100 51 102 0 1179938160 26262352 12141584
06:23:39 110623 8192000000 10101610352 10568126464 1106377024 11.898 96 26 52 0 1184458000 72479600 5601376
06:26:33 91333 8192000000 10072094928 10519044096 1108353952 11.917 97 31 60 1 1187176592 71913552 6909088
06:29:07 83597 8192000000 10090096064 10558799872 1139597568 12.212 100 12 25 1 1201775600 59668928 2509104
06:31:52 148801 8192000000 10090147712 10563469312 1129666256 12.118 99 18 37 0 1202482224 68878976 3936992
06:35:40 77876 8192000000 10045931248 10515644416 1127201568 12.095 99 17 34 0 1179084432 48348576 3534288
06:38:00 36180 8192000000 10076970656 10542587904 1145848896 12.271 100 40 80 0 1199615920 44865712 8901312
06:39:47 35475 8192000000 10078165008 10528382976 1120664528 12.033 97 21 42 0 1158298800 33331296 4302976
06:41:24 59897 8192000000 10089298928 10551300096 1105062640 11.886 97 22 44 0 1166016480 56478384 4475456
06:44:55 115329 8192000000 10070253456 10544291840 1116120464 11.990 99 17 34 0 1182761472 62916528 3724480
06:47:53 112153 8192000000 10138299392 10620960768 1107764080 11.911 97 17 33 1 1185689360 74466288 3458992
06:50:47 88561 8192000000 10047030976 10493603840 1069801888 11.550 95 14 28 0 1116040112 43221488 3016736
06:53:16 90629 8192000000 10094943056 10529091584 1086018464 11.705 97 19 37 2 1139224768 48925920 4280384
06:55:47 19823 8192000000 10122888880 10557607936 1125397120 12.078 99 64 123 2 1219876960 81761824 12718016
06:57:15 8191 8192000000 10065058816 10490167296 1101797104 11.855 99 56 107 1 1156726944 44633312 10296528
06:58:25 40544 8192000000 10171274080 10618634240 1118298256 12.011 99 34 69 1 1256907312 131009744 7599312
07:00:46 43517 8192000000 10162990720 10650001408 1129058160 12.112 100 16 31 0 1280004784 146594736 4351888
07:02:33 51982 8192000000 10077020768 10531061760 1107864096 11.912 98 30 60 2 1159814976 45205904 6744976
07:04:29 101142 8192000000 10047629920 10500874240 1096776720 11.807 97 22 44 0 1135966096 34689072 4500304
07:07:17 63545 8192000000 10054868912 10556538880 1109798816 11.931 99 10 20 0 1167581984 54677600 3105568
07:09:31 51173 8192000000 10066987744 10519068672 1112147232 11.953 99 14 28 0 1161860496 46921408 2791856
07:11:23 65280 8192000000 10053465408 10534420480 1114383280 11.974 99 10 20 0 1153895392 37076992 2435120
07:13:48 32248 8192000000 10049007504 10503680000 1123349088 12.059 100 20 40 0 1164473280 36991584 4132608
07:15:22 62789 8192000000 10128505152 10573455360 1142071728 12.235 100 36 72 0 1221459968 72102272 7285968
07:17:32 32337 8192000000 10071452448 10523852800 1113662816 11.967 98 30 58 2 1149836992 29926736 6247440
07:19:06 32305 8192000000 10098341024 10539393024 1127393488 12.097 99 27 53 2 1175159312 42406144 5359680
07:20:41 26365 8192000000 10112076576 10556469248 1127527264 12.098 99 40 78 0 1192725856 57159424 8039168
07:22:12 26688 8192000000 10089697168 10532720640 1133233840 12.152 100 40 75 0 1192533920 51015584 8284496
07:23:40 21733 8192000000 10158119776 10598662144 1121652096 12.043 98 39 78 0 1228826560 99132848 8041616
07:25:08 104391 8192000000 10079099104 10522349568 1115064496 11.980 98 9 18 0 1176231584 59121040 2046048
07:27:54 93960 8192000000 10060813040 10535575552 1126218160 12.086 99 28 56 0 1189388672 57639296 5531216
07:30:34 8764 8192000000 10083794640 10506850304 1122944192 12.055 99 62 121 0 1176111312 40728288 12438832
07:31:47 69935 8192000000 10064804288 10516267008 1108719296 11.920 98 12 24 1 1155674080 44066752 2888032
07:33:58 48082 8192000000 10070067440 10538184704 1110340352 11.936 98 11 22 0 1173806144 60953872 2511920
07:35:46 50368 8192000000 10055639056 10534883328 1091467872 11.757 97 12 24 2 1169074288 74802304 2804112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-04-2022 07:38:44 to 18-04-2022 07:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-04-2022 07:39:01 to 18-04-2022 07:39:03.

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          215611
MON$OLDEST_ACTIVE               216100
MON$OLDEST_SNAPSHOT             216100
MON$NEXT_TRANSACTION            216100
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                       2111168
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

18.04.22 07:39:04. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-18 07:39:06 No severe PSQL-related problems occured
18.04.22 07:39:08. End of report.