Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.08.2022 10: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 15696256 kB
MemAvailable 30979796 kB
Buffers 0 kB
Cached 15988752 kB
SwapCached 2512 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-08-01 10:37:17
DTS_END                         2022-08-01 10:37:17.1550
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(68:17)
IP                              ::1/33738
TRN_ID                          216646
ATT_ID                          593
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: 8861.39 28161 1063367
customer order: creation 160.63 499 19275
customer order: refuse 14.12 407 1694
order to supplier: creation 43.45 1329 5214
order to supplier: removal 3.40 166859 408
invoice (draft): creation 43.64 3034 5237
invoice (draft): removal 50.53 956 6064
invoice accept: apply 40.11 26796 4813
invoice accept: cancel 45.38 1149 5445
customer reserve: creation 3718.78 258 446254
customer reserve: removal 4465.77 100 535893
realization accept: apply 78.39 255 9407
realization accept: cancel 74.56 161 8947
payment from customer: creation 56.28 178 6753
payment from customer: removal 5.64 116 677
payment to supplier: creation 45.52 195 5462
payment to supplier: removal 3.21 115 385
service: total inventory turnovers 3.84 3727 461
service: total monetary turnovers 7.33 368 879
service: aggregate perf. data 0.79 16208 95
service: refresh index statistics 0.03 340512 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-08-2022 10:38:26 to 01-08-2022 10:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10623
TEST_TIME 2 7036
TEST_TIME 3 6125
TEST_TIME 4 6501
TEST_TIME 5 7069
TEST_TIME 6 7106
TEST_TIME 7 7101
TEST_TIME 8 7053
TEST_TIME 9 6695
TEST_TIME 10 6622
TEST_TIME 11 6393
TEST_TIME 12 6415
TEST_TIME 13 6541
TEST_TIME 14 6475
TEST_TIME 15 6607
TEST_TIME 16 6829
TEST_TIME 17 6795
TEST_TIME 18 7037
TEST_TIME 19 7227
TEST_TIME 20 7285
TEST_TIME 21 7548
TEST_TIME 22 7522
TEST_TIME 23 7728
TEST_TIME 24 7779
TEST_TIME 25 8137
TEST_TIME 26 8426
TEST_TIME 27 9122
TEST_TIME 28 9689
TEST_TIME 29 10018
TEST_TIME 30 9930
TEST_TIME 31 10497
TEST_TIME 32 11003
TEST_TIME 33 10881
TEST_TIME 34 10701
TEST_TIME 35 10788
TEST_TIME 36 10867
TEST_TIME 37 10879
TEST_TIME 38 10843
TEST_TIME 39 10605
TEST_TIME 40 10367
TEST_TIME 41 10312
TEST_TIME 42 10197
TEST_TIME 43 10110
TEST_TIME 44 9976
TEST_TIME 45 9857
TEST_TIME 46 9846
TEST_TIME 47 9715
TEST_TIME 48 9654
TEST_TIME 49 9687
TEST_TIME 50 9668
TEST_TIME 51 9560
TEST_TIME 52 9390
TEST_TIME 53 9348
TEST_TIME 54 9300
TEST_TIME 55 9298
TEST_TIME 56 9269
TEST_TIME 57 9239
TEST_TIME 58 9203
TEST_TIME 59 9231
TEST_TIME 60 9283
TEST_TIME 61 9242
TEST_TIME 62 9200
TEST_TIME 63 9251
TEST_TIME 64 9338
TEST_TIME 65 9348
TEST_TIME 66 9317
TEST_TIME 67 9286
TEST_TIME 68 9317
TEST_TIME 69 9406
TEST_TIME 70 9354
TEST_TIME 71 9268
TEST_TIME 72 9167
TEST_TIME 73 9194
TEST_TIME 74 9169
TEST_TIME 75 9098
TEST_TIME 76 9089
TEST_TIME 77 9047
TEST_TIME 78 9090
TEST_TIME 79 9040
TEST_TIME 80 9086
TEST_TIME 81 9055
TEST_TIME 82 9048
TEST_TIME 83 9043
TEST_TIME 84 9038
TEST_TIME 85 9111
TEST_TIME 86 9179
TEST_TIME 87 9274
TEST_TIME 88 9278
TEST_TIME 89 9290
TEST_TIME 90 9268
TEST_TIME 91 9190
TEST_TIME 92 9159
TEST_TIME 93 9172
TEST_TIME 94 9114
TEST_TIME 95 9066
TEST_TIME 96 9048
TEST_TIME 97 9017
TEST_TIME 98 8991
TEST_TIME 99 8964
TEST_TIME 100 8982
TEST_TIME 101 9026
TEST_TIME 102 9048
TEST_TIME 103 9036
TEST_TIME 104 9054
TEST_TIME 105 9091
TEST_TIME 106 9083
TEST_TIME 107 9114
TEST_TIME 108 9092
TEST_TIME 109 9025
TEST_TIME 110 8988
TEST_TIME 111 9017
TEST_TIME 112 8997
TEST_TIME 113 8963
TEST_TIME 114 8937
TEST_TIME 115 8958
TEST_TIME 116 8941
TEST_TIME 117 8978
TEST_TIME 118 8947
TEST_TIME 119 8911
TEST_TIME 120 8887

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-08-2022 10:38:30 to 01-08-2022 10:38:33.

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 1475605 1475605 0 0.00 0 23721 13 0 0
doc_list_biud 1475600 1475600 0 0.00 0 25742 42 0 0
sp_add_invoice_to_stock 4894 4813 81 1.65 34 268929 26796 0 81
sp_cancel_adding_invoice 5445 5445 0 0.00 6 69283 1149 0 0
sp_cancel_client_order 1694 1694 0 0.00 5 9287 407 0 0
sp_cancel_customer_reserve 535893 535893 0 0.00 1 25364 100 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 3136 116 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 2624 115 0 0
sp_cancel_supplier_invoice 6074 6064 10 0.16 2 25750 957 0 10
sp_cancel_supplier_order 408 408 0 0.00 9 1409055 166859 0 0
sp_cancel_write_off 8947 8947 0 0.00 2 11961 161 0 0
sp_client_order 19275 19275 0 0.00 5 40017 499 0 0
sp_customer_reserve 446254 446254 0 0.00 0 89491 258 0 0
sp_fill_shopping_cart 29721 29721 0 0.00 1 22449 84 0 0
sp_fill_shopping_cart_clo_res 446233 446233 0 0.00 0 18409 20 0 0
sp_get_clo_for_invoice 4779 4779 0 0.00 0 42390 274 0 0
sp_kill_cost_storno 15454 15454 0 0.00 0 7917 71 0 0
sp_kill_qstorno_ret_qs2qd 1087205 1087205 0 0.00 0 25739 44 0 0
sp_kill_qty_storno 572637 572637 0 0.00 0 25741 100 0 0
sp_lock_selected_doc 19738 19738 0 0.00 0 8566 8 0 0
sp_make_cost_storno 26401 26401 0 0.00 0 23713 74 0 0
sp_multiply_rows_for_pdistr 26401 26401 0 0.00 0 8057 38 0 0
sp_multiply_rows_for_qdistr 19275 19275 0 0.00 0 39927 217 0 0
sp_pay_from_customer 6753 6753 0 0.00 5 15948 178 0 0
sp_pay_to_supplier 5462 5462 0 0.00 6 14483 195 0 0
sp_payment_common 12214 12214 0 0.00 2 7738 46 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 1 9175 333 0 0
sp_qd_handle_on_invoice_upd_sts 10224 10224 0 0.00 0 18525 486 0 0
sp_qd_handle_on_reserve_upd_sts 9407 9407 0 0.00 0 16928 58 0 0
sp_reserve_write_off 9407 9407 0 0.00 8 16947 255 0 0
sp_supplier_invoice 5237 5237 0 0.00 8 105237 3035 0 0
sp_supplier_order 5214 5214 0 0.00 7 38410 1330 0 0
srv_aggregate_perf_data 95 95 0 0.00 2306 136538 16209 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 795 138786 41010 0 0
srv_find_qd_qs_mism 1017493 1017493 0 0.00 0 11019 10 0 0
srv_make_invnt_saldo 461 461 0 0.00 42 83714 3727 0 0
srv_make_money_saldo 879 879 0 0.00 1 13246 369 0 0
srv_recalc_idx_stat 4 4 0 0.00 98350 546909 340513 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 0 39342 850 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 2 10875 451 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 20650 52 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 3072 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 2721 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 832 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1891 41 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 70495 6870 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 3329 24 0 0
t$perf-norm:sp_client_order 19275 19275 0 0.00 0 13462 36 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 13683 27 0 0
t$perf-norm:sp_pay_from_customer 6753 6753 0 0.00 0 6081 20 0 0
t$perf-norm:sp_pay_to_supplier 5462 5462 0 0.00 0 7770 25 0 0
t$perf-norm:sp_reserve_write_off 9408 9408 0 0.00 0 4849 21 0 0
t$perf-norm:sp_supplier_invoice 5237 5237 0 0.00 0 4426 29 0 0
t$perf-norm:sp_supplier_order 5214 5214 0 0.00 0 5967 31 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 296 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 352 15 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 650 17 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 5272 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 160 59 0 0
v_add_invoice_to_stock 9717 9555 162 1.66 0 9708 26 0 162
v_all_customers 35272 35272 0 0.00 0 168 3 0 0
v_all_suppliers 28990 28990 0 0.00 0 167 4 0 0
v_all_wares 38550 38550 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 665 14 0 0
v_cancel_client_order 3388 3388 0 0.00 0 3633 12 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 452 10 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 862 10 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 594 10 0 0
v_cancel_supplier_invoice 818 798 20 2.44 0 5306 30 0 20
v_cancel_supplier_order 816 816 0 0.00 0 4062 28 0 0
v_cancel_write_off 1932 1932 0 0.00 0 8402 27 0 0
v_max_id_clo_ord 5213 5213 0 0.00 0 1235 5 0 0
v_max_id_clo_res 971 971 0 0.00 0 7239 25 0 0
v_max_id_ord_sup 5233 5233 0 0.00 0 3653 7 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 5349 11 0 0
v_max_non_paid_realizn 3570 3570 0 0.00 0 3392 8 0 0
v_min_id_clo_ord 5213 5213 0 0.00 0 1927 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 2401 15 0 0
v_min_id_ord_sup 5233 5233 0 0.00 0 6193 9 0 0
v_min_non_paid_invoice 5461 5461 0 0.00 0 7721 21 0 0
v_min_non_paid_realizn 6753 6753 0 0.00 0 4815 20 0 0
v_our_firm 9642 9642 0 0.00 0 170 4 0 0
v_reserve_write_off 18814 18814 0 0.00 0 9712 11 0 0
x_lock_depdocs_on_canc_invoice 11110 11110 0 0.00 0 20429 123 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 2729 327 0 0
x_make_qstorno_1000_1200 5214 5214 0 0.00 2 38367 1183 0 0
x_make_qstorno_1200_2000 5237 5237 0 0.00 4 102379 2779 0 0
x_make_qstorno_2100_3300 446251 446251 0 0.00 0 89490 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-08-2022 10:38:34 to 01-08-2022 10:38:38.

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:21 2 8192000000 8797233264 8807895040 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 31759 8192000000 9726739680 10025394176 801404560 8.911 100 93 186 1 832431248 7619744 23406944
08:10:01 14706 8192000000 9778442224 10092105728 854177392 9.442 100 89 176 1 887699936 12489328 21033216
08:11:20 14517 8192000000 9819363248 10141405184 884213440 9.742 100 86 172 0 923841408 20167872 19460096
08:12:40 11128 8192000000 9879941456 10214547456 925445088 10.150 100 80 158 2 989648320 47195472 17007760
08:14:01 3414 8192000000 9932971776 10276642816 958110400 10.471 100 86 172 1 1048898560 72570592 18217568
08:15:07 1623 8192000000 9960946608 10312511488 991593952 10.797 100 90 171 1 1073969904 64671136 17704816
08:16:10 1700 8192000000 9983539696 10348597248 1019375104 11.066 100 92 171 0 1106158976 69479184 17304688
08:17:13 1251 8192000000 10009010496 10380808192 1041172112 11.276 100 92 177 0 1125269808 65507680 18590016
08:18:16 902 8192000000 10036827360 10410078208 1057926096 11.437 100 96 189 1 1152332544 75407568 18998880
08:19:18 7051 8192000000 10018277248 10394812416 1065551392 11.510 100 83 161 1 1139851056 59395472 14904192
08:20:27 1003 8192000000 10044994864 10425692160 1075940992 11.609 100 85 161 0 1166329424 73700128 16688304
08:21:28 5670 8192000000 10012581280 10406309888 1080237392 11.650 100 75 144 0 1143604384 48421280 14945712
08:22:35 1023 8192000000 10042086704 10427879424 1083883136 11.684 100 93 160 4 1166474976 65458176 17133664
08:23:37 10420 8192000000 10054603888 10446868480 1085455120 11.699 100 62 118 0 1165059824 68310144 11294560
08:24:48 1169 8192000000 10047192000 10434088960 1090606064 11.748 100 96 187 1 1167353312 57741856 19005392
08:25:50 1041 8192000000 10063049440 10449248256 1091690512 11.759 100 95 182 0 1186624800 75995792 18938496
08:26:52 1164 8192000000 10070327152 10455879680 1086685264 11.711 100 89 172 0 1190974080 86245120 18043696
08:27:54 764 8192000000 10025541024 10413735936 1081371776 11.661 100 86 164 0 1143930240 45254288 17304176
08:28:55 1707 8192000000 10025924112 10418626560 1087195776 11.716 100 68 122 5 1150554672 49436736 13922160
08:29:58 825 8192000000 10027532832 10414751744 1081635120 11.663 100 90 172 1 1148717168 49190944 17891104
08:30:59 1145 8192000000 10025525712 10406350848 1051796992 11.378 99 95 179 3 1140307776 70083520 18427264
08:32:01 1140 8192000000 10016650960 10397036544 1054972944 11.408 100 96 177 2 1136118464 63572160 17573360
08:33:03 1351 8192000000 10002245840 10380632064 1046288160 11.325 100 89 175 2 1126021440 60566032 19167248
08:34:05 1208 8192000000 10002735616 10379534336 1042191888 11.286 100 83 155 0 1121342304 64291040 14859376
08:35:08 1064 8192000000 9971878912 10353885184 1046525808 11.327 100 78 138 1 1102301616 40615696 15160112
08:36:09 3008 8192000000 9969923248 10342002688 1020833584 11.080 98 71 132 3 1087903616 53572016 13498016
08:37:13 20197 8192000000 9993763600 10384924672 1053223840 11.392 100 49 91 2 1124191248 62118272 8849136
08:38:41 3237 8192000000 10031487936 10421764096 1085538624 11.700 100 78 150 5 1154418128 53410144 15469360
08:39:51 19936 8192000000 10065052560 10460749824 1093401568 11.775 100 67 131 0 1165313152 59066688 12844896
08:41:18 1848 8192000000 10093872224 10480783360 1107555856 11.909 100 83 162 0 1217234368 93054928 16623584
08:42:24 957 8192000000 10125165568 10514444288 1121832880 12.044 100 95 182 0 1246384800 103660960 20890960
08:43:28 3348 8192000000 10162593168 10562961408 1133187648 12.151 100 76 146 2 1276740288 128806144 14746496
08:44:35 2898 8192000000 10199840368 10613583872 1145820624 12.270 100 69 135 0 1315508144 155128816 14558704
08:45:44 10108 8192000000 10203247152 10619080704 1164734720 12.448 100 62 109 0 1324043920 147033936 12275264
08:46:59 3784 8192000000 10250862800 10658648064 1174756800 12.541 100 80 151 1 1354066848 162737712 16572336
08:48:06 7857 8192000000 10309201392 10716987392 1187683200 12.662 100 77 148 1 1417514304 213109968 16721136
08:49:19 1528 8192000000 10345991520 10745692160 1188199904 12.667 99 94 180 0 1456353920 247393520 20760496
08:50:24 1824 8192000000 10398069312 10812436480 1195844656 12.738 100 90 174 0 1505389536 288938112 20606768
08:51:28 1214 8192000000 10423889680 10828935168 1197165344 12.750 100 93 174 1 1537231616 318995952 21070320
08:52:34 1881 8192000000 10448336016 10866081792 1201699520 12.792 100 89 170 0 1556457152 334918816 19838816
08:53:41 1717 8192000000 10486765632 10914902016 1218665520 12.949 100 93 179 1 1591040400 351671040 20703840
08:54:46 825 8192000000 10500948992 10936819712 1216679056 12.931 100 97 189 3 1606516432 366453120 23384256
08:55:50 3002 8192000000 10508545088 10938580992 1213775840 12.904 100 86 161 1 1613728976 381026976 18926160
08:56:58 784 8192000000 10524307968 10943631360 1212874224 12.896 100 99 190 1 1632359808 397112080 22373504
08:58:03 1299 8192000000 10541711984 10985488384 1212164160 12.889 100 96 180 0 1647270544 412360112 22746272
08:59:06 8526 8192000000 10556079504 10992304128 1210637696 12.875 100 59 110 1 1662945168 437730608 14576864
09:00:18 2486 8192000000 10580308976 11029069824 1190168768 12.685 100 66 132 1 1684788544 477827808 16791968
09:01:27 3658 8192000000 10600016112 11051356160 1190843728 12.691 100 70 135 0 1703102928 495124896 17134304
09:02:32 4551 8192000000 10569448800 11060293632 1178573872 12.577 99 63 123 0 1694797552 499814832 16408848
09:03:46 10724 8192000000 10536058096 11019472896 1186142800 12.647 100 52 104 1 1636595280 436913184 13539296
09:05:01 15139 8192000000 10384356192 10926714880 1149420880 12.304 99 43 86 0 1493647952 333245840 10981232
09:06:18 26750 8192000000 10245439584 10732781568 1145568928 12.268 100 31 62 0 1368521872 214787392 8165552
09:07:46 33139 8192000000 10246468768 10803351552 1130124400 12.123 99 17 34 0 1387608064 253228080 4255584
09:09:48 81879 8192000000 10053396032 10495066112 1092326432 11.765 96 5 8 0 1139815088 46995024 493632
09:12:10 30463 8192000000 10096550224 10561290240 1110212640 11.934 98 21 42 0 1198887472 84505056 4169776
09:13:42 138785 8192000000 10041754000 10483716096 1009310656 10.969 89 13 26 1 1035020880 23019552 2690672
09:17:01 56626 8192000000 10073401344 10491879424 1095610064 11.796 98 56 94 0 1142213344 36992016 9611264
09:19:04 24268 8192000000 10084554176 10514563072 1115154672 11.981 99 46 88 2 1157342480 33086592 9101216
09:20:31 23698 8192000000 10083186000 10521595904 1124045744 12.065 100 50 93 0 1189929616 56340064 9543808
09:21:55 88753 8192000000 10071290976 10549157888 1096075616 11.800 97 21 37 0 1174444384 74160928 4207840
09:24:37 85542 8192000000 10090149360 10539429888 1077047200 11.619 96 17 34 0 1156259168 75442864 3769104
09:27:06 60106 8192000000 10025801472 10506665984 1104969040 11.885 99 20 40 0 1154325920 45352464 4004416
09:29:06 61582 8192000000 10037988096 10473230336 1102775616 11.864 99 33 60 1 1139868288 30976480 6116192
09:31:09 85144 8192000000 10048586272 10569216000 1112452688 11.956 99 11 22 2 1177534992 62151120 2931184
09:33:36 39020 8192000000 10051308384 10484654080 1091284832 11.755 98 24 48 0 1124881312 28745616 4850864
09:35:30 44936 8192000000 10114069088 10557763584 1128712608 12.109 100 40 77 0 1204769520 68061760 7995152
09:37:18 63181 8192000000 10069485104 10508156928 1118114848 12.009 99 19 37 0 1186705296 64484688 4105760
09:39:22 35706 8192000000 10131618352 10570588160 1112914448 11.960 98 34 68 0 1207452144 87818224 6719472
09:41:02 65486 8192000000 10093936032 10546905088 1091662256 11.758 96 21 42 1 1189273984 93454256 4157472
09:43:07 12080 8192000000 10101892320 10525523968 1139988480 12.215 100 56 103 2 1189421936 38886032 10547424
09:44:20 32803 8192000000 10152534672 10577293312 1132089024 12.141 99 60 118 0 1220359904 76369152 11901728
09:45:54 83092 8192000000 10095619824 10554384384 1119286368 12.020 98 30 55 1 1194938048 69809008 5842672
09:48:24 63703 8192000000 10081578016 10525040640 1120557696 12.032 98 22 44 0 1175232128 50490816 4183616
09:50:32 57910 8192000000 10097700224 10617569280 1120295200 12.030 98 11 22 0 1187129840 64494592 2340048
09:53:00 106430 8192000000 10131145296 10614050816 1137926048 12.196 99 17 34 0 1220550688 79034736 3589904
09:55:49 95663 8192000000 10106933744 10583863296 1105987344 11.894 96 18 36 1 1159591424 49270368 4333712
09:58:48 65594 8192000000 10151828912 10594684928 1115173904 11.981 97 28 53 0 1196627760 75747200 5706656
10:00:59 41927 8192000000 10204322992 10695450624 1127493248 12.098 98 28 56 0 1286746160 153209824 6043088
10:03:20 61292 8192000000 10118831536 10609913856 1124663104 12.071 99 28 56 0 1272201632 141392304 6146224
10:05:21 74459 8192000000 10095595248 10544254976 1069613536 11.548 94 31 57 1 1117265952 42176000 5476416
10:07:39 135531 8192000000 10102373904 10569445376 1125717632 12.081 99 38 75 1 1198501200 64753680 8029888
10:10:55 55538 8192000000 10057485616 10541248512 1135446656 12.173 100 5 10 0 1165075104 28941088 687360
10:12:52 64066 8192000000 10093649008 10555633664 1107727760 11.911 96 13 26 0 1161923008 51568784 2626464
10:14:56 65404 8192000000 10133703744 10589900800 1113159344 11.962 98 29 55 0 1204175088 85206912 5808832
10:17:03 16225 8192000000 10156472576 10596257792 1131572128 12.136 99 31 62 0 1245573840 107287712 6714000
10:18:28 95813 8192000000 10090470064 10530508800 1097832912 11.817 97 22 44 0 1186118416 83094112 5191392
10:21:09 71757 8192000000 10139139136 10579206144 1116119168 11.990 98 34 68 2 1201913264 78964208 6829888
10:23:37 58524 8192000000 10092167216 10562379776 1093442048 11.775 97 34 66 1 1173630912 73828576 6360288
10:25:38 85223 8192000000 10126679248 10563563520 1103379776 11.870 98 50 101 0 1187532352 74094400 10058176
10:28:34 55565 8192000000 10068762624 10603692032 1118318640 12.011 100 5 10 0 1187826320 68606544 901136
10:30:32 50141 8192000000 10074205728 10522992640 1115886656 11.988 99 31 61 1 1157658592 35516928 6255008
10:32:26 25001 8192000000 10069307696 10515578880 1120175712 12.029 99 18 34 1 1162355312 38473568 3706032
10:33:56 69240 8192000000 10081357776 10587471872 1109366624 11.926 98 20 40 0 1170755408 56795424 4593360
10:36:17 67059 8192000000 9512425872 9887178752 585481888 6.670 52 16 32 0 636137696 47222848 3432960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-08-2022 10:38:40 to 01-08-2022 10:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-08-2022 10:38:57 to 01-08-2022 10:38:59.

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          214758
MON$OLDEST_ACTIVE               217402
MON$OLDEST_SNAPSHOT             217402
MON$NEXT_TRANSACTION            217402
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                       2067104
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:395: 
:396: mscs-test-161.budzdorov.ru    Mon Aug  1 10:37:29 2022
:397:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:398: 
--- end of diff output ---

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

01.08.22 10:39:00. 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-08-01 10:39:02 No severe PSQL-related problems occured
01.08.22 10:39:04. End of report.