Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2022 10: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 16422664 kB
MemAvailable 31337108 kB
Buffers 0 kB
Cached 15307024 kB
SwapCached 1176 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.1
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-01-11 10:37:11
DTS_END                         2022-01-11 10:37:11.8900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43430
TRN_ID                          224954
ATT_ID                          672
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: 8850.38 28817 1062045
customer order: creation 172.53 478 20703
customer order: refuse 14.47 385 1736
order to supplier: creation 46.67 1341 5600
order to supplier: removal 3.39 160580 407
invoice (draft): creation 45.76 2893 5491
invoice (draft): removal 47.50 934 5700
invoice accept: apply 42.35 26718 5082
invoice accept: cancel 43.08 1180 5170
customer reserve: creation 3889.47 260 466736
customer reserve: removal 4252.54 102 510305
realization accept: apply 85.82 238 10298
realization accept: cancel 75.97 182 9117
payment from customer: creation 58.22 147 6986
payment from customer: removal 5.93 102 711
payment to supplier: creation 50.01 168 6001
payment to supplier: removal 3.60 110 432
service: total inventory turnovers 4.42 3529 530
service: total monetary turnovers 7.49 336 899
service: aggregate perf. data 1.14 11824 137
service: refresh index statistics 0.03 364823 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-01-2022 10:39:59 to 11-01-2022 10:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9045
TEST_TIME 2 6591
TEST_TIME 3 5859
TEST_TIME 4 5535
TEST_TIME 5 5949
TEST_TIME 6 5610
TEST_TIME 7 5474
TEST_TIME 8 5492
TEST_TIME 9 5762
TEST_TIME 10 6135
TEST_TIME 11 6394
TEST_TIME 12 6482
TEST_TIME 13 6488
TEST_TIME 14 6906
TEST_TIME 15 7006
TEST_TIME 16 7195
TEST_TIME 17 7512
TEST_TIME 18 7587
TEST_TIME 19 7644
TEST_TIME 20 7925
TEST_TIME 21 8474
TEST_TIME 22 8354
TEST_TIME 23 8470
TEST_TIME 24 8652
TEST_TIME 25 8818
TEST_TIME 26 8957
TEST_TIME 27 9755
TEST_TIME 28 10027
TEST_TIME 29 10767
TEST_TIME 30 10862
TEST_TIME 31 10968
TEST_TIME 32 11068
TEST_TIME 33 10997
TEST_TIME 34 10945
TEST_TIME 35 10666
TEST_TIME 36 10705
TEST_TIME 37 10566
TEST_TIME 38 10394
TEST_TIME 39 10259
TEST_TIME 40 10225
TEST_TIME 41 10113
TEST_TIME 42 9924
TEST_TIME 43 9963
TEST_TIME 44 9869
TEST_TIME 45 9931
TEST_TIME 46 9782
TEST_TIME 47 9641
TEST_TIME 48 9686
TEST_TIME 49 9729
TEST_TIME 50 9643
TEST_TIME 51 9547
TEST_TIME 52 9613
TEST_TIME 53 9623
TEST_TIME 54 9514
TEST_TIME 55 9495
TEST_TIME 56 9564
TEST_TIME 57 9606
TEST_TIME 58 9558
TEST_TIME 59 9608
TEST_TIME 60 9561
TEST_TIME 61 9496
TEST_TIME 62 9478
TEST_TIME 63 9381
TEST_TIME 64 9314
TEST_TIME 65 9276
TEST_TIME 66 9176
TEST_TIME 67 9139
TEST_TIME 68 9072
TEST_TIME 69 9057
TEST_TIME 70 9072
TEST_TIME 71 9036
TEST_TIME 72 9062
TEST_TIME 73 9054
TEST_TIME 74 9091
TEST_TIME 75 9201
TEST_TIME 76 9154
TEST_TIME 77 9142
TEST_TIME 78 9259
TEST_TIME 79 9232
TEST_TIME 80 9194
TEST_TIME 81 9192
TEST_TIME 82 9124
TEST_TIME 83 9070
TEST_TIME 84 9053
TEST_TIME 85 8985
TEST_TIME 86 8918
TEST_TIME 87 8919
TEST_TIME 88 8876
TEST_TIME 89 8889
TEST_TIME 90 8919
TEST_TIME 91 8906
TEST_TIME 92 8936
TEST_TIME 93 8930
TEST_TIME 94 8927
TEST_TIME 95 8925
TEST_TIME 96 8890
TEST_TIME 97 8886
TEST_TIME 98 8991
TEST_TIME 99 9001
TEST_TIME 100 8920
TEST_TIME 101 8918
TEST_TIME 102 8863
TEST_TIME 103 8810
TEST_TIME 104 8807
TEST_TIME 105 8820
TEST_TIME 106 8800
TEST_TIME 107 8777
TEST_TIME 108 8749
TEST_TIME 109 8732
TEST_TIME 110 8720
TEST_TIME 111 8706
TEST_TIME 112 8727
TEST_TIME 113 8708
TEST_TIME 114 8686
TEST_TIME 115 8737
TEST_TIME 116 8807
TEST_TIME 117 8926
TEST_TIME 118 8970
TEST_TIME 119 8949
TEST_TIME 120 8880

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2022 10:40:03 to 11-01-2022 10:40:06.

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 1491347 1491347 0 0.00 0 27368 14 0 0
doc_list_biud 1491347 1491347 0 0.00 0 36771 40 0 0
sp_add_invoice_to_stock 5223 5082 141 2.69 60 210368 26719 0 141
sp_cancel_adding_invoice 5170 5170 0 0.00 8 77809 1181 0 0
sp_cancel_client_order 1736 1736 0 0.00 6 17967 386 0 0
sp_cancel_customer_reserve 510305 510305 0 0.00 1 36775 103 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 5738 103 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 5559 111 0 0
sp_cancel_supplier_invoice 5715 5700 15 0.26 3 30194 935 0 15
sp_cancel_supplier_order 407 407 0 0.00 8 1324086 160580 0 0
sp_cancel_write_off 9117 9117 0 0.00 2 11180 182 0 0
sp_client_order 20703 20703 0 0.00 5 60142 479 0 0
sp_customer_reserve 466736 466736 0 0.00 1 64072 261 0 0
sp_fill_shopping_cart 31791 31791 0 0.00 1 16051 78 0 0
sp_fill_shopping_cart_clo_res 466704 466704 0 0.00 0 16567 18 0 0
sp_get_clo_for_invoice 5031 5031 0 0.00 0 33719 320 0 0
sp_kill_cost_storno 15430 15430 0 0.00 0 27362 77 0 0
sp_kill_qstorno_ret_qs2qd 1035834 1035834 0 0.00 0 36761 45 0 0
sp_kill_qty_storno 547760 547760 0 0.00 0 36770 102 0 0
sp_lock_selected_doc 21158 21158 0 0.00 0 9862 6 0 0
sp_make_cost_storno 28312 28312 0 0.00 0 12404 70 0 0
sp_multiply_rows_for_pdistr 28312 28312 0 0.00 0 10271 33 0 0
sp_multiply_rows_for_qdistr 20703 20703 0 0.00 0 27901 202 0 0
sp_pay_from_customer 6986 6986 0 0.00 5 8636 147 0 0
sp_pay_to_supplier 6001 6001 0 0.00 7 11577 168 0 0
sp_payment_common 12987 12987 0 0.00 2 8624 39 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 1 17959 328 0 0
sp_qd_handle_on_invoice_upd_sts 10197 10197 0 0.00 0 20295 486 0 0
sp_qd_handle_on_reserve_upd_sts 10298 10298 0 0.00 0 16509 57 0 0
sp_reserve_write_off 10298 10298 0 0.00 8 16563 239 0 0
sp_supplier_invoice 5491 5491 0 0.00 8 80698 2893 0 0
sp_supplier_order 5600 5600 0 0.00 7 43820 1342 0 0
srv_aggregate_perf_data 137 137 0 0.00 2220 165431 11824 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 880 129723 35999 0 0
srv_find_qd_qs_mism 1012494 1012494 0 0.00 0 20672 12 0 0
srv_make_invnt_saldo 530 530 0 0.00 53 173640 3530 0 0
srv_make_money_saldo 899 899 0 0.00 1 13963 336 0 0
srv_recalc_idx_stat 4 4 0 0.00 332287 405812 364823 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 1 17427 825 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 8760 425 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 4461 30 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1122 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 309 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 1792 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1328 28 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 97585 6125 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 1052 15 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 12192 32 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 4700 24 0 0
t$perf-norm:sp_pay_from_customer 6986 6986 0 0.00 0 6047 19 0 0
t$perf-norm:sp_pay_to_supplier 6001 6001 0 0.00 0 27017 28 0 0
t$perf-norm:sp_reserve_write_off 10298 10298 0 0.00 0 20235 27 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 10245 30 0 0
t$perf-norm:sp_supplier_order 5600 5600 0 0.00 0 27191 31 0 0
t$perf-norm:srv_aggregate_perf_data 137 137 0 0.00 0 364 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 169 7 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 769 11 0 0
t$perf-norm:srv_make_money_saldo 899 899 0 0.00 0 379 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 136 40 0 0
v_add_invoice_to_stock 10336 10054 282 2.72 0 4602 20 0 282
v_all_customers 37614 37614 0 0.00 0 242 3 0 0
v_all_suppliers 31070 31070 0 0.00 0 245 4 0 0
v_all_wares 41406 41406 0 0.00 0 62 3 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 338 12 0 0
v_cancel_client_order 3472 3472 0 0.00 0 1671 8 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 307 8 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 1004 9 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 255 8 0 0
v_cancel_supplier_invoice 864 834 30 3.47 0 679 18 0 30
v_cancel_supplier_order 814 814 0 0.00 0 1972 14 0 0
v_cancel_write_off 2064 2064 0 0.00 0 2569 20 0 0
v_max_id_clo_ord 5600 5600 0 0.00 0 1231 4 0 0
v_max_id_clo_res 997 997 0 0.00 0 1366 14 0 0
v_max_id_ord_sup 5488 5488 0 0.00 0 4657 8 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 3976 8 0 0
v_max_non_paid_realizn 3723 3723 0 0.00 0 188 6 0 0
v_min_id_clo_ord 5600 5600 0 0.00 0 1593 7 0 0
v_min_id_clo_res 997 997 0 0.00 0 333 7 0 0
v_min_id_ord_sup 5488 5488 0 0.00 0 9184 8 0 0
v_min_non_paid_invoice 6001 6001 0 0.00 0 7569 16 0 0
v_min_non_paid_realizn 6986 6986 0 0.00 0 7432 13 0 0
v_our_firm 10318 10318 0 0.00 0 119 4 0 0
v_reserve_write_off 20596 20596 0 0.00 0 4847 9 0 0
x_lock_depdocs_on_canc_invoice 10453 10453 0 0.00 0 16749 131 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 6320 313 0 0
x_make_qstorno_1000_1200 5600 5600 0 0.00 3 43728 1188 0 0
x_make_qstorno_1200_2000 5491 5491 0 0.00 4 70507 2669 0 0
x_make_qstorno_2100_3300 466736 466736 0 0.00 0 64039 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 10:40:07 to 11-01-2022 10:40:11.

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
08:07:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:16 13888 8192000000 9701001792 9994432512 785714976 8.751 100 90 180 1 815674672 7619472 22340224
08:09:32 24076 8192000000 9746031888 10053058560 827130656 9.170 100 85 170 0 859311776 11654208 20526912
08:11:14 12433 8192000000 9792643312 10111717376 868127632 9.581 100 82 165 0 904303440 17339568 18836240
08:12:29 11554 8192000000 9864784688 10193604608 907261664 9.970 100 84 167 0 971367648 45695584 18410400
08:13:44 2859 8192000000 9943793232 10281361408 954054992 10.431 100 94 181 1 1049581584 75717488 19809104
08:14:52 3556 8192000000 9954757840 10304905216 985117648 10.734 100 83 163 2 1071442736 69553904 16771184
08:15:59 1065 8192000000 9996817120 10353741824 1005674640 10.934 100 90 171 0 1115534928 92208768 17651520
08:17:02 996 8192000000 10018370912 10383798272 1031539552 11.183 100 97 186 3 1140053328 90953632 17560144
08:18:05 1234 8192000000 10013512640 10382909440 1046841552 11.330 100 95 184 1 1133803248 69190032 17771664
08:19:09 1403 8192000000 10038000496 10410385408 1052808576 11.388 100 96 185 0 1157871072 86944128 18118368
08:20:12 1356 8192000000 10017361824 10391273472 1061097952 11.467 100 96 188 2 1137178512 55567392 20513168
08:21:15 1312 8192000000 10034296640 10407272448 1066415712 11.518 100 95 180 3 1153011504 67767072 18828720
08:22:19 1512 8192000000 10020271312 10400944128 1067795600 11.531 100 73 137 2 1151118128 70381792 12940736
08:23:23 1278 8192000000 10035432368 10418847744 1075643152 11.606 100 97 186 0 1162883456 68919424 18320880
08:24:25 1227 8192000000 10045150368 10424774656 1081439600 11.661 100 96 187 2 1164221344 62563376 20218368
08:25:27 1062 8192000000 10040561584 10424975360 1087191792 11.716 100 94 182 1 1161207088 56038272 17977024
08:26:29 1316 8192000000 10064246832 10450366464 1092314096 11.765 100 92 182 1 1184040624 72827264 18899264
08:27:31 1674 8192000000 10032684832 10419752960 1088411536 11.728 100 75 147 0 1155952880 51588288 15953056
08:28:34 2542 8192000000 10057788816 10446307328 1089381360 11.737 100 78 149 2 1179284672 75538416 14364896
08:29:37 972 8192000000 10059187872 10443124736 1073352240 11.584 99 91 172 2 1179755296 88176560 18226496
08:30:39 1219 8192000000 10047068016 10424508416 1059088032 11.448 100 95 183 0 1169262480 91570032 18604416
08:31:42 1231 8192000000 10017570704 10395369472 1053045184 11.390 100 96 184 0 1137841808 66415104 18381520
08:32:44 1060 8192000000 10010468880 10382979072 1051339392 11.374 100 88 165 0 1134652336 66271232 17041712
08:33:47 1192 8192000000 9971498288 10345193472 1038180080 11.247 100 86 165 0 1093577952 36715680 18682192
08:34:48 1252 8192000000 9992141456 10367655936 1039510608 11.260 99 90 176 3 1114374992 56414704 18449680
08:35:50 1751 8192000000 9997045648 10370830336 1035086976 11.217 100 82 162 1 1115030416 63280336 16663104
08:36:53 1269 8192000000 9967738208 10339872768 1029467408 11.163 100 85 156 3 1089466176 43451552 16547216
08:38:02 16100 8192000000 9987257024 10366955520 1039729312 11.262 99 61 120 0 1107935248 56306608 11899328
08:39:31 13163 8192000000 10022482192 10403774464 1066572768 11.519 100 67 129 1 1145637280 66067424 12997088
08:40:50 1596 8192000000 10044406112 10428481536 1088702592 11.730 100 67 124 0 1162209248 60655264 12851392
08:41:56 24150 8192000000 10123818816 10514391040 1100417744 11.842 100 76 150 0 1227019408 110025888 16575776
08:43:27 1186 8192000000 10138155856 10529894400 1120174832 12.029 100 81 149 1 1259069920 124165056 14730032
08:44:36 1586 8192000000 10198571760 10586226688 1129092432 12.113 100 86 162 0 1317141488 171494400 16554656
08:45:41 2552 8192000000 10212446336 10619256832 1143538496 12.249 100 78 148 0 1323791744 163249232 17004016
08:46:46 3169 8192000000 10275968144 10680696832 1161750016 12.420 100 90 175 0 1390104624 209085312 19269296
08:47:54 873 8192000000 10285571728 10692624384 1177797744 12.570 100 92 183 0 1401604688 203455392 20351552
08:48:57 1026 8192000000 10316894656 10716270592 1176935808 12.562 100 92 177 1 1426651520 230146016 19569696
08:50:02 2370 8192000000 10365395264 10771402752 1182767024 12.616 100 91 176 0 1484362864 280900640 20695200
08:51:11 1785 8192000000 10381576640 10808307712 1184614608 12.633 100 93 182 2 1486230640 280798704 20817328
08:52:16 1095 8192000000 10423302080 10824151040 1191883808 12.701 100 90 178 0 1533073392 320682272 20507312
08:53:18 871 8192000000 10456800368 10864832512 1186955712 12.655 100 99 194 2 1561992960 352920048 22117200
08:54:22 1002 8192000000 10474826752 10890547200 1194309808 12.723 100 97 183 0 1579608256 361886288 23412160
08:55:25 1222 8192000000 10486324816 10909945856 1180727248 12.597 100 86 168 0 1598044368 396870768 20446352
08:56:32 981 8192000000 10498167520 10925432832 1185340960 12.640 100 95 181 0 1605805440 398061088 22403392
08:57:34 917 8192000000 10466951472 10928574464 1175717344 12.550 99 95 185 1 1572858032 375485200 21655488
08:58:37 1035 8192000000 10503737776 10931949568 1208349360 12.854 100 81 158 1 1609847856 383075552 18422944
08:59:42 8120 8192000000 10501073984 10930831360 1192948896 12.711 100 53 102 0 1608783136 402675120 13159120
09:00:58 8842 8192000000 10530630128 10965618688 1190319520 12.686 100 42 82 0 1643757440 442560352 10877568
09:02:09 14121 8192000000 10547462992 10999111680 1179872176 12.589 100 40 80 0 1667572112 476838336 10861600
09:03:34 13628 8192000000 10488937360 10953695232 1161352976 12.416 100 33 65 2 1596793360 426679568 8760816
09:04:53 42316 8192000000 10372846080 10846277632 1151291632 12.322 100 21 42 1 1482801488 326271856 5238000
09:06:47 51480 8192000000 10127658144 10563149824 1084304736 11.688 94 23 46 1 1185780944 96220368 5255840
09:08:40 46273 8192000000 10142594128 10613149696 1128735872 12.109 99 9 19 0 1212346656 81530416 2080368
09:10:37 31840 8192000000 10101436352 10524520448 1110160720 11.934 98 52 97 1 1186582784 66336528 10085536
09:12:09 64964 8192000000 10082028080 10509684736 1096834928 11.808 97 54 100 2 1152941872 44978112 11128832
09:14:24 129722 8192000000 10075987808 10521174016 1093501904 11.776 96 21 41 1 1157528896 59857056 4169936
09:17:43 63375 8192000000 10093645232 10586345472 1126182112 12.085 99 10 20 0 1203657600 75370128 2105360
09:19:49 51193 8192000000 10161497072 10609029120 1123435952 12.059 99 18 36 1 1249904032 121842912 4625168
09:21:40 61620 8192000000 10064658208 10556383232 1105437408 11.889 98 21 42 0 1184688624 74484352 4766864
09:24:08 104615 8192000000 10108569760 10593247232 1107361840 11.907 98 30 60 1 1201412592 87171440 6879312
09:28:07 18452 8192000000 10096051520 10529542144 1129899152 12.120 100 37 72 1 1190671376 53530032 7242192
09:29:26 47703 8192000000 10079397328 10534412288 1132389488 12.144 100 20 40 0 1188687248 52404912 3892848
09:31:18 31337 8192000000 10100297792 10533593088 1142008464 12.234 100 38 77 0 1192600336 42874416 7717456
09:32:52 13039 8192000000 10107904032 10550136832 1132603696 12.146 100 52 103 0 1212897104 69202816 11090592
09:34:10 61200 8192000000 10071643888 10505113600 1077635600 11.625 95 40 75 4 1129644736 44390880 7618256
09:36:28 82359 8192000000 10086505344 10536820736 1088762480 11.731 96 37 74 2 1134984096 38831520 7390096
09:38:55 128816 8192000000 10069748448 10491969536 1130567248 12.127 100 51 102 0 1226319584 84796704 10955632
09:42:17 40723 8192000000 10058348112 10508890112 1128726368 12.109 100 14 28 0 1172140880 40711824 2702688
09:43:58 46662 8192000000 10070505968 10543112192 1122840512 12.054 99 13 27 0 1164877984 39271536 2765936
09:45:52 60621 8192000000 10114751888 10570620928 1139513056 12.211 100 18 34 1 1220080176 76960560 3606560
09:47:54 36711 8192000000 10148500352 10585538560 1145675984 12.269 99 31 59 4 1228598080 76686800 6235296
09:49:31 29290 8192000000 10119347760 10552827904 1110554160 11.938 97 28 56 1 1187343168 70820800 5968208
09:51:01 95227 8192000000 10106203328 10587860992 1113010192 11.961 97 23 46 1 1196876336 79350800 4515344
09:53:37 31128 8192000000 10126922128 10553204736 1115377872 11.983 97 41 82 0 1177769040 54064032 8327136
09:55:09 52138 8192000000 10081763280 10537586688 1131987728 12.140 99 22 44 0 1208076656 71728736 4360192
09:57:10 6507 8192000000 10099826928 10526027776 1145409696 12.266 100 62 122 0 1186523920 28673856 12440368
09:58:17 8364 8192000000 10135915408 10558808064 1136660720 12.184 99 56 112 1 1223632224 75804880 11166624
09:59:29 80070 8192000000 10059898976 10507927552 1114800704 11.978 97 34 68 1 1178003152 56188624 7013824
10:01:53 41214 8192000000 10046124496 10501292032 1094167952 11.782 96 6 12 0 1124803424 29510592 1124880
10:03:47 77960 8192000000 10074761888 10553016320 1120630080 12.033 99 10 20 0 1158585152 36037024 1918048
10:06:06 29029 8192000000 10082923712 10550505472 1119564960 12.023 99 41 79 1 1167137664 38095568 9477136
10:07:41 78391 8192000000 10095854496 10549555200 1114054896 11.971 98 15 30 0 1177340544 60215984 3069664
10:10:00 32220 8192000000 10078744288 10518274048 1126637296 12.090 100 47 94 0 1169036688 33246448 9152944
10:11:32 45426 8192000000 10114185824 10553581568 1114107936 11.971 98 40 78 0 1195240240 73664448 7467856
10:13:28 46371 8192000000 10134147744 10555490304 1077050192 11.619 96 47 88 1 1165293264 78886256 9356816
10:15:17 85746 8192000000 10068954928 10532216832 1085148688 11.697 97 16 31 1 1149959136 61662752 3147696
10:17:43 48406 8192000000 10069066080 10521268224 1126487936 12.088 100 23 43 1 1176761696 46728704 3545056
10:19:33 88850 8192000000 10087013680 10561101824 1119349792 12.021 99 12 24 0 1170000800 48616112 2034896
10:22:02 58243 8192000000 10073471776 10542825472 1108960992 11.923 98 19 38 0 1146308112 33408416 3938704
10:24:07 31117 8192000000 10090319920 10522214400 1093091424 11.772 97 34 68 1 1145026320 44600528 7334368
10:25:43 36128 8192000000 10139512640 10565054464 1123250624 12.058 99 39 77 2 1214116016 82137168 8728224
10:27:20 23119 8192000000 10179530000 10615689216 1129255072 12.114 99 45 90 2 1259131888 119582240 10294576
10:28:46 11153 8192000000 10221858944 10653028352 1147653968 12.287 100 51 99 1 1310103840 151420976 11028896
10:29:57 22158 8192000000 10223147456 10656505856 1151125264 12.320 99 32 64 1 1297920528 139865088 6930176
10:31:21 14045 8192000000 10291619248 10724638720 1164325008 12.444 100 57 112 0 1389278416 212444736 12508672
10:32:51 44100 8192000000 10250277088 10736439296 1122574256 12.051 97 26 51 1 1339515504 210559936 6381312
10:34:47 36697 8192000000 10155461536 10604953600 1119381744 12.021 98 34 66 0 1242173792 115486272 7305776
10:36:27 66178 8192000000 9618446976 10099929088 704762544 7.921 61 12 23 0 807514320 99871376 2880400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-01-2022 10:40:13 to 11-01-2022 10:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2022 10:40:30 to 11-01-2022 10:40:32.

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          224500
MON$OLDEST_ACTIVE               225372
MON$OLDEST_SNAPSHOT             225372
MON$NEXT_TRANSACTION            225372
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                       2046752
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:319: 
:320: mscs-test-161.budzdorov.ru    Tue Jan 11 10:37:59 2022
:321:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:322: 
--- end of diff output ---

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

11.01.22 10:40:33. 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-01-11 10:40:36 No severe PSQL-related problems occured
11.01.22 10:40:37. End of report.