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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15627716 kB
MemAvailable 31018740 kB
Buffers 0 kB
Cached 16061444 kB
SwapCached 2552 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-07-25 13:37:20
DTS_END                         2022-07-25 13:37:20.2200
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/40570
TRN_ID                          224241
ATT_ID                          640
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: 9133.72 24672 1096046
customer order: creation 174.64 489 20957
customer order: refuse 14.98 429 1797
order to supplier: creation 45.73 1369 5488
order to supplier: removal 3.28 158346 394
invoice (draft): creation 45.33 3158 5439
invoice (draft): removal 51.66 865 6199
invoice accept: apply 41.83 25332 5019
invoice accept: cancel 46.03 1127 5523
customer reserve: creation 3910.58 239 469269
customer reserve: removal 4505.79 90 540695
realization accept: apply 85.07 282 10208
realization accept: cancel 74.47 164 8936
payment from customer: creation 61.60 189 7392
payment from customer: removal 5.71 115 685
payment to supplier: creation 49.93 198 5992
payment to supplier: removal 3.65 106 438
service: total inventory turnovers 4.39 3489 527
service: total monetary turnovers 8.03 326 964
service: aggregate perf. data 0.99 12718 119
service: refresh index statistics 0.04 284412 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-07-2022 13:38:28 to 25-07-2022 13:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6401
TEST_TIME 2 4554
TEST_TIME 3 4461
TEST_TIME 4 4685
TEST_TIME 5 4913
TEST_TIME 6 5385
TEST_TIME 7 5596
TEST_TIME 8 6139
TEST_TIME 9 6319
TEST_TIME 10 6247
TEST_TIME 11 6305
TEST_TIME 12 6513
TEST_TIME 13 6593
TEST_TIME 14 6780
TEST_TIME 15 6873
TEST_TIME 16 7153
TEST_TIME 17 7250
TEST_TIME 18 7370
TEST_TIME 19 7466
TEST_TIME 20 7670
TEST_TIME 21 7660
TEST_TIME 22 7703
TEST_TIME 23 7913
TEST_TIME 24 7941
TEST_TIME 25 8186
TEST_TIME 26 8460
TEST_TIME 27 8991
TEST_TIME 28 8949
TEST_TIME 29 9715
TEST_TIME 30 10015
TEST_TIME 31 10382
TEST_TIME 32 11110
TEST_TIME 33 10962
TEST_TIME 34 11024
TEST_TIME 35 11075
TEST_TIME 36 10960
TEST_TIME 37 10964
TEST_TIME 38 11039
TEST_TIME 39 11069
TEST_TIME 40 10995
TEST_TIME 41 10831
TEST_TIME 42 10721
TEST_TIME 43 10628
TEST_TIME 44 10601
TEST_TIME 45 10448
TEST_TIME 46 10326
TEST_TIME 47 10225
TEST_TIME 48 10199
TEST_TIME 49 10064
TEST_TIME 50 10043
TEST_TIME 51 10321
TEST_TIME 52 10219
TEST_TIME 53 10161
TEST_TIME 54 10167
TEST_TIME 55 10007
TEST_TIME 56 10000
TEST_TIME 57 9867
TEST_TIME 58 9801
TEST_TIME 59 9805
TEST_TIME 60 9755
TEST_TIME 61 9857
TEST_TIME 62 9773
TEST_TIME 63 9830
TEST_TIME 64 9779
TEST_TIME 65 9812
TEST_TIME 66 9865
TEST_TIME 67 9858
TEST_TIME 68 9895
TEST_TIME 69 9986
TEST_TIME 70 9942
TEST_TIME 71 9892
TEST_TIME 72 10004
TEST_TIME 73 9882
TEST_TIME 74 9838
TEST_TIME 75 9774
TEST_TIME 76 9721
TEST_TIME 77 9695
TEST_TIME 78 9621
TEST_TIME 79 9573
TEST_TIME 80 9493
TEST_TIME 81 9432
TEST_TIME 82 9425
TEST_TIME 83 9367
TEST_TIME 84 9412
TEST_TIME 85 9462
TEST_TIME 86 9453
TEST_TIME 87 9431
TEST_TIME 88 9428
TEST_TIME 89 9440
TEST_TIME 90 9440
TEST_TIME 91 9482
TEST_TIME 92 9505
TEST_TIME 93 9514
TEST_TIME 94 9535
TEST_TIME 95 9510
TEST_TIME 96 9427
TEST_TIME 97 9458
TEST_TIME 98 9387
TEST_TIME 99 9435
TEST_TIME 100 9412
TEST_TIME 101 9418
TEST_TIME 102 9380
TEST_TIME 103 9342
TEST_TIME 104 9319
TEST_TIME 105 9351
TEST_TIME 106 9333
TEST_TIME 107 9427
TEST_TIME 108 9460
TEST_TIME 109 9461
TEST_TIME 110 9447
TEST_TIME 111 9406
TEST_TIME 112 9350
TEST_TIME 113 9323
TEST_TIME 114 9289
TEST_TIME 115 9237
TEST_TIME 116 9212
TEST_TIME 117 9157
TEST_TIME 118 9193
TEST_TIME 119 9195
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-07-2022 13:38:32 to 25-07-2022 13:38:35.

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 1528368 1528368 0 0.00 0 14667 13 0 0
doc_list_biud 1528362 1528362 0 0.00 0 28846 37 0 0
sp_add_invoice_to_stock 5103 5019 84 1.64 35 214938 25332 0 84
sp_cancel_adding_invoice 5523 5523 0 0.00 6 105871 1128 0 0
sp_cancel_client_order 1797 1797 0 0.00 6 24818 430 0 0
sp_cancel_customer_reserve 540695 540695 0 0.00 1 27519 90 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 4 2268 116 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 4 2467 107 0 0
sp_cancel_supplier_invoice 6203 6199 4 0.06 3 15431 866 0 4
sp_cancel_supplier_order 394 394 0 0.00 8 1571775 158347 0 0
sp_cancel_write_off 8936 8936 0 0.00 2 16748 164 0 0
sp_client_order 20957 20957 0 0.00 5 28832 490 0 0
sp_customer_reserve 469269 469269 0 0.00 0 54662 239 0 0
sp_fill_shopping_cart 31873 31873 0 0.00 1 17441 77 0 0
sp_fill_shopping_cart_clo_res 469249 469249 0 0.00 0 22512 18 0 0
sp_get_clo_for_invoice 4996 4996 0 0.00 0 24347 286 0 0
sp_kill_cost_storno 15582 15582 0 0.00 0 9177 71 0 0
sp_kill_qstorno_ret_qs2qd 1096919 1096919 0 0.00 0 27478 38 0 0
sp_kill_qty_storno 578747 578747 0 0.00 0 28845 90 0 0
sp_lock_selected_doc 21003 21003 0 0.00 0 10944 9 0 0
sp_make_cost_storno 28588 28588 0 0.00 0 13401 79 0 0
sp_multiply_rows_for_pdistr 28587 28587 0 0.00 0 13214 41 0 0
sp_multiply_rows_for_qdistr 20957 20957 0 0.00 0 8818 185 0 0
sp_pay_from_customer 7392 7392 0 0.00 5 13325 189 0 0
sp_pay_to_supplier 5992 5992 0 0.00 6 22684 198 0 0
sp_payment_common 13384 13384 0 0.00 2 7943 47 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 24802 342 0 0
sp_qd_handle_on_invoice_upd_sts 10518 10518 0 0.00 0 28843 505 0 0
sp_qd_handle_on_reserve_upd_sts 10208 10208 0 0.00 0 27083 68 0 0
sp_reserve_write_off 10208 10208 0 0.00 8 27173 283 0 0
sp_supplier_invoice 5439 5439 0 0.00 10 88803 3159 0 0
sp_supplier_order 5488 5488 0 0.00 7 43239 1369 0 0
srv_aggregate_perf_data 119 119 0 0.00 2111 120965 12719 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 706 130306 36546 0 0
srv_find_qd_qs_mism 1046310 1046310 0 0.00 0 11342 10 0 0
srv_make_invnt_saldo 527 527 0 0.00 44 70685 3490 0 0
srv_make_money_saldo 964 964 0 0.00 1 11755 326 0 0
srv_recalc_idx_stat 5 5 0 0.00 111292 474579 284412 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5021 5021 0 0.00 1 17504 928 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 5002 424 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 1484 31 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 2380 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 2011 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 2904 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1018 31 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 72742 6688 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 3340 22 0 0
t$perf-norm:sp_client_order 20957 20957 0 0.00 0 5848 37 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 785 15 0 0
t$perf-norm:sp_pay_from_customer 7392 7392 0 0.00 0 5224 19 0 0
t$perf-norm:sp_pay_to_supplier 5992 5992 0 0.00 0 4532 24 0 0
t$perf-norm:sp_reserve_write_off 10209 10209 0 0.00 0 8570 22 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 5405 28 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 3667 31 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 721 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1317 31 0 0
t$perf-norm:srv_make_invnt_saldo 527 527 0 0.00 0 2054 13 0 0
t$perf-norm:srv_make_money_saldo 964 964 0 0.00 0 684 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 214 74 0 0
v_add_invoice_to_stock 10158 9990 168 1.65 0 4821 24 0 168
v_all_customers 38716 38716 0 0.00 0 61 4 0 0
v_all_suppliers 30672 30672 0 0.00 0 115 4 0 0
v_all_wares 41912 41912 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1500 19 0 0
v_cancel_client_order 3594 3594 0 0.00 0 4800 14 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2249 16 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2609 15 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 388 10 0 0
v_cancel_supplier_invoice 816 808 8 0.98 0 1169 20 0 8
v_cancel_supplier_order 788 788 0 0.00 0 932 13 0 0
v_cancel_write_off 1988 1988 0 0.00 0 3464 27 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 374 5 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 559 8 0 0
v_max_id_ord_sup 5430 5430 0 0.00 0 6240 9 0 0
v_max_non_paid_invoice 1572 1572 0 0.00 0 3170 10 0 0
v_max_non_paid_realizn 3843 3843 0 0.00 0 381 7 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 4319 9 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 5141 14 0 0
v_min_id_ord_sup 5430 5430 0 0.00 0 6750 8 0 0
v_min_non_paid_invoice 5992 5992 0 0.00 0 7933 16 0 0
v_min_non_paid_realizn 7392 7392 0 0.00 0 6821 21 0 0
v_our_firm 10294 10294 0 0.00 0 60 5 0 0
v_reserve_write_off 20416 20416 0 0.00 0 5658 11 0 0
x_lock_depdocs_on_canc_invoice 11318 11318 0 0.00 0 15469 113 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 13311 373 0 0
x_make_qstorno_1000_1200 5488 5488 0 0.00 2 43164 1212 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 5 88712 2955 0 0
x_make_qstorno_2100_3300 469268 469268 0 0.00 0 48013 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-07-2022 13:38:36 to 25-07-2022 13:38:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:24 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:25 23673 8192000000 9714489264 10011750400 792265648 8.818 100 85 170 0 821583968 7891344 21426976
11:09:55 17584 8192000000 9748021280 10054008832 822939856 9.128 100 88 176 0 857095216 12878320 21277040
11:11:18 10187 8192000000 9781022752 10093744128 846677744 9.367 100 86 170 0 888077648 21340816 20059088
11:12:39 8667 8192000000 9832059872 10163994624 886508192 9.764 100 71 136 1 941018000 39279472 15230336
11:13:51 3811 8192000000 9907022544 10247262208 945511712 10.347 100 88 171 1 1020008048 55972848 18523488
11:14:58 1339 8192000000 9955180048 10306076672 990886400 10.790 100 91 175 0 1068283616 59424224 17972992
11:16:03 1433 8192000000 9988867632 10351316992 1015300640 11.027 100 91 179 2 1111252288 77712080 18239568
11:17:06 1248 8192000000 10009430560 10376089600 1035405152 11.220 100 83 159 1 1137033456 86918640 14709664
11:18:09 895 8192000000 10012476720 10384326656 1053117152 11.391 100 94 173 3 1133837872 63117376 17603344
11:19:11 1191 8192000000 10045579968 10422837248 1060813264 11.464 100 95 183 0 1168051328 88762688 18475376
11:20:14 2387 8192000000 10045745552 10442190848 1066484480 11.518 100 65 128 1 1173965040 93890896 13589664
11:21:17 1465 8192000000 10040147088 10429521920 1076762672 11.617 100 85 163 0 1167418576 73284944 17370960
11:22:20 1114 8192000000 10058470416 10449141760 1085935648 11.704 100 93 179 0 1183417216 78278080 19203488
11:23:22 1112 8192000000 10061787552 10450702336 1084927888 11.694 100 98 189 1 1184203264 78892880 20382496
11:24:23 1010 8192000000 10069370464 10459332608 1092324304 11.765 100 92 178 1 1190996112 80619936 18051872
11:25:25 2208 8192000000 10051269568 10447015936 1091808704 11.760 100 73 124 5 1170241472 66579904 11852864
11:26:29 5405 8192000000 10033569616 10428080128 1095742784 11.797 100 62 116 1 1160697040 53455632 11498624
11:27:36 1210 8192000000 10039295504 10431295488 1097456496 11.814 100 92 172 2 1158753568 43560576 17736496
11:28:39 26112 8192000000 10033932080 10447233024 1074496208 11.595 98 56 110 0 1153690432 68321584 10872640
11:30:07 1047 8192000000 10012765408 10396844032 1068164000 11.535 100 97 182 1 1136145696 50164976 17816720
11:31:09 1085 8192000000 10013324432 10396110848 1059358144 11.450 100 93 168 0 1135571456 57922432 18290880
11:32:11 8529 8192000000 9991333488 10380193792 1049848080 11.359 100 59 116 2 1116736704 55131968 11756656
11:33:20 1519 8192000000 9983513856 10364354560 1028939072 11.158 99 76 131 0 1109063024 66142800 13981152
11:34:24 1159 8192000000 9962149408 10335358976 1026226016 11.132 100 78 153 2 1090466000 48384288 15855696
11:35:26 866 8192000000 9974373376 10349264896 1037321664 11.239 100 87 166 2 1097037920 42749360 16966896
11:36:28 1004 8192000000 9938867712 10317758464 1030774544 11.176 99 75 137 5 1062042000 14985424 16282032
11:37:30 1070 8192000000 9969931744 10342309888 1041311056 11.277 100 90 152 3 1087035248 30459968 15264224
11:38:37 27001 8192000000 10000388256 10392883200 1064154416 11.496 100 43 82 0 1117861088 45536528 8170144
11:40:09 5339 8192000000 10033045584 10419945472 1086953024 11.714 100 66 126 1 1145816416 46002784 12860608
11:41:18 4983 8192000000 10070738832 10465083392 1097409296 11.813 100 71 138 1 1195857312 84270896 14177120
11:42:28 3887 8192000000 10107287904 10505986048 1110188432 11.934 100 83 158 0 1231692288 105582704 15921152
11:43:34 1177 8192000000 10152807808 10544558080 1126436080 12.088 100 84 149 0 1272253728 130196608 15621040
11:44:39 1786 8192000000 10155002128 10559012864 1135627584 12.174 100 70 138 1 1276109504 125515248 14966672
11:45:44 3406 8192000000 10196284608 10597920768 1147891200 12.290 100 88 166 1 1311549408 145112880 18545328
11:46:53 3138 8192000000 10229952864 10635857920 1164906624 12.449 100 85 158 1 1341789920 159157040 17726256
11:48:18 1595 8192000000 10247091536 10650574848 1174321936 12.537 100 83 159 1 1358935888 166592416 18021536
11:49:22 1138 8192000000 10296889904 10699022336 1184195504 12.629 100 95 182 1 1409974128 204998608 20780016
11:50:26 993 8192000000 10336693600 10744975360 1187121680 12.657 100 97 192 0 1447620896 239242816 21256400
11:51:29 2223 8192000000 10365702992 10772557824 1189982144 12.683 100 91 179 1 1477133104 267307184 19843776
11:52:37 1833 8192000000 10369013584 10796802048 1202124880 12.796 100 89 172 0 1488530128 266168016 20237232
11:53:43 1025 8192000000 10411689312 10817155072 1201654480 12.792 100 93 184 0 1518275488 295716192 20904816
11:54:47 2619 8192000000 10450052832 10869587968 1207605040 12.847 100 87 171 1 1555009104 326600880 20803184
11:55:52 1180 8192000000 10474808448 10895876096 1199725728 12.774 99 91 171 1 1580537024 359727184 21084112
11:56:55 2834 8192000000 10519682864 10944417792 1195300528 12.733 100 87 171 3 1620285440 404469584 20515328
11:58:00 872 8192000000 10540669776 10949939200 1194624192 12.726 100 99 192 0 1647054048 428909920 23519936
11:59:04 696 8192000000 10586851600 10997608448 1187185536 12.657 100 97 191 1 1691989088 481378736 23424816
12:00:09 1298 8192000000 10545632304 10985811968 1171345760 12.509 100 95 187 0 1649254640 454570896 23337984
12:01:12 3528 8192000000 10560096736 11024125952 1170284800 12.499 100 59 116 0 1665888272 480942400 14661072
12:02:30 2751 8192000000 10578309936 11019304960 1161472464 12.417 100 70 137 0 1683323616 505260768 16590384
12:03:55 6916 8192000000 10524665840 11004219392 1146176784 12.274 99 56 112 0 1630489760 469976880 14336096
12:05:05 11247 8192000000 10488051728 10937118720 1147730656 12.288 100 37 71 1 1593429840 436279616 9419568
12:06:18 6181 8192000000 10442617200 10992644096 1148398640 12.294 100 45 90 1 1543676320 383486544 11791136
12:07:33 13523 8192000000 10345799072 10838413312 1137900800 12.196 100 35 67 3 1445970768 299292304 8777664
12:08:47 49364 8192000000 10138948304 10684719104 1120423760 12.031 100 18 36 0 1290756272 165498256 4834256
12:11:11 99571 8192000000 10069585584 10536554496 1097552208 11.814 97 14 27 0 1193637104 93434512 2650384
12:13:52 48304 8192000000 10108484832 10580197376 1122671264 12.052 99 10 18 0 1206376656 81814576 1890816
12:15:42 7210 8192000000 10059864416 10493628416 1140708288 12.222 100 33 56 0 1157440384 11548384 5183712
12:16:52 72406 8192000000 10093537072 10539937792 1146614912 12.278 100 10 18 0 1180193568 31002128 2576528
12:19:09 22220 8192000000 10126744592 10546843648 1129228272 12.114 99 59 112 1 1204456816 64091280 11137264
12:20:31 44978 8192000000 10071805248 10506137600 1127814160 12.101 99 46 73 1 1162457168 27504752 7138256
12:22:20 43376 8192000000 10138621920 10578857984 1153224336 12.340 100 59 109 0 1220265632 55883520 11157776
12:24:05 23104 8192000000 10133996816 10593808384 1142497200 12.239 99 18 36 0 1227443344 81005376 3940768
12:25:28 51338 8192000000 10190427904 10617212928 1133990800 12.159 98 42 84 0 1261197104 117487664 9718640
12:27:29 21789 8192000000 10255282128 10720833536 1144663920 12.259 100 44 85 1 1343537120 188900080 9973120
12:28:54 36118 8192000000 10107561712 10617778176 1131800560 12.138 99 14 28 0 1199868864 65133712 2934592
12:31:08 13135 8192000000 10081284528 10540564480 1139858752 12.214 100 29 48 4 1185565232 38122000 7584480
12:32:23 29524 8192000000 10085471216 10532454400 1128602864 12.108 99 34 67 1 1177014544 41180112 7231568
12:33:52 60937 8192000000 10100386272 10561449984 1137542560 12.192 99 19 38 0 1174611648 33450640 3618448
12:36:03 114754 8192000000 10104122320 10560528384 1112351904 11.955 97 12 24 0 1200250976 85404432 2494640
12:39:56 3379 8192000000 10111594208 10549665792 1139682288 12.213 100 54 109 0 1211798544 61040208 11076048
12:41:01 28878 8192000000 10113138000 10557259776 1127762704 12.100 99 34 63 1 1194841712 60159232 6919776
12:42:30 59926 8192000000 10097182320 10570788864 1116401536 11.993 98 19 38 0 1198261632 77876464 3983632
12:44:32 19743 8192000000 10111764336 10544476160 1114132496 11.972 98 38 74 1 1188796064 67253776 7409792
12:45:53 97698 8192000000 10058814096 10531930112 1084302064 11.688 96 26 52 0 1163943440 74369504 5271872
12:48:31 83964 8192000000 10109583120 10593492992 1113480528 11.965 99 26 52 0 1186521968 68018208 5023232
12:50:56 73891 8192000000 10075574256 10552852480 1127281344 12.096 99 36 66 0 1179659952 45211440 7167168
12:53:25 48966 8192000000 10054635456 10514251776 1124217920 12.067 99 15 31 0 1158865424 31522224 3125280
12:55:20 59402 8192000000 10043669472 10497495040 1107113536 11.905 98 5 8 0 1125267296 17396304 757456
12:57:21 115265 8192000000 10077225440 10567266304 1057284128 11.430 93 17 29 0 1117385968 57258944 2842896
13:00:59 86916 8192000000 10091012880 10586664960 1111554800 11.947 98 21 41 3 1180880192 65065760 4259632
13:03:31 33751 8192000000 10075858880 10520276992 1118334208 12.011 99 31 62 0 1159806304 35168208 6303888
13:05:10 56602 8192000000 10085491024 10558480384 1125673760 12.081 100 12 24 0 1177543488 49369232 2500496
13:07:07 33667 8192000000 10104179632 10563272704 1122330624 12.049 99 40 80 0 1194093744 63523776 8239344
13:08:41 38578 8192000000 10083931168 10549170176 1124682432 12.071 99 36 72 1 1210790016 79122784 6984800
13:10:23 56920 8192000000 10111113056 10571927552 1131607808 12.137 99 23 44 0 1224095808 87365104 5122896
13:12:22 130305 8192000000 10110203184 10600632320 1142431856 12.238 100 33 66 0 1211199968 61904304 6863808
13:15:44 68061 8192000000 10079984416 10657554432 1117464112 12.003 98 6 12 0 1193696528 75260736 971680
13:17:55 123724 8192000000 10082904544 10539249664 1088102144 11.725 95 10 20 0 1122500368 32307152 2091072
13:21:02 44392 8192000000 10135479648 10592186368 1118915744 12.017 97 32 63 1 1221022544 95139968 6966832
13:22:53 95367 8192000000 10164998832 10645413888 1137339184 12.190 99 25 50 0 1262137648 119422736 5375728
13:25:29 75103 8192000000 10069442576 10546831360 1084656640 11.692 95 24 43 1 1131782848 42575328 4550880
13:27:45 31418 8192000000 10064352928 10514092032 1079821040 11.646 96 39 74 0 1135795568 48361888 7612640
13:29:17 73375 8192000000 10066675456 10492841984 1110988672 11.942 99 44 87 0 1165419216 44966688 9463856
13:31:31 46377 8192000000 10052972608 10511974400 1103031936 11.866 98 12 24 0 1147479392 42119392 2328064
13:33:29 55927 8192000000 10078351520 10544795648 1115297024 11.983 100 7 14 0 1181297616 64559424 1441168
13:35:30 64028 8192000000 10042368128 10530598912 1110933264 11.941 99 6 12 0 1158621104 46320528 1367312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-07-2022 13:38:42 to 25-07-2022 13:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-07-2022 13:38:59 to 25-07-2022 13:39:01.

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          224240
MON$OLDEST_ACTIVE               224954
MON$OLDEST_SNAPSHOT             224954
MON$NEXT_TRANSACTION            224954
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                       2075680
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

25.07.22 13:39:02. 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-07-25 13:39:04 No severe PSQL-related problems occured
25.07.22 13:39:06. End of report.