Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2021 13:37
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 16585636 kB
MemAvailable 31412576 kB
Buffers 0 kB
Cached 15073240 kB
SwapCached 0 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 2021-12-17 13:37:11
DTS_END                         2021-12-17 13:37:11.1380
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/51624
TRN_ID                          240879
ATT_ID                          621
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: 8994.20 24013 1079304
customer order: creation 169.91 385 20389
customer order: refuse 14.73 352 1768
order to supplier: creation 45.28 1314 5433
order to supplier: removal 3.18 114794 382
invoice (draft): creation 45.84 3717 5501
invoice (draft): removal 50.39 900 6047
invoice accept: apply 41.71 18458 5005
invoice accept: cancel 45.50 827 5460
customer reserve: creation 3814.59 182 457751
customer reserve: removal 4466.40 65 535968
realization accept: apply 84.24 193 10109
realization accept: cancel 83.09 117 9971
payment from customer: creation 59.28 143 7114
payment from customer: removal 5.86 90 703
payment to supplier: creation 49.78 155 5973
payment to supplier: removal 3.48 67 418
service: total inventory turnovers 3.86 3444 463
service: total monetary turnovers 6.08 542 729
service: aggregate perf. data 0.96 13087 115
service: refresh index statistics 0.04 321437 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-12-2021 13:37:59 to 17-12-2021 13:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2803
TEST_TIME 2 3967
TEST_TIME 3 5551
TEST_TIME 4 5766
TEST_TIME 5 5878
TEST_TIME 6 5719
TEST_TIME 7 5539
TEST_TIME 8 5394
TEST_TIME 9 5607
TEST_TIME 10 6099
TEST_TIME 11 6171
TEST_TIME 12 6863
TEST_TIME 13 7332
TEST_TIME 14 7496
TEST_TIME 15 7434
TEST_TIME 16 7417
TEST_TIME 17 7810
TEST_TIME 18 8341
TEST_TIME 19 8874
TEST_TIME 20 8853
TEST_TIME 21 8857
TEST_TIME 22 9059
TEST_TIME 23 9395
TEST_TIME 24 9477
TEST_TIME 25 10006
TEST_TIME 26 11438
TEST_TIME 27 11138
TEST_TIME 28 11170
TEST_TIME 29 10885
TEST_TIME 30 10722
TEST_TIME 31 10677
TEST_TIME 32 10610
TEST_TIME 33 10423
TEST_TIME 34 10197
TEST_TIME 35 10099
TEST_TIME 36 10070
TEST_TIME 37 10082
TEST_TIME 38 10034
TEST_TIME 39 9927
TEST_TIME 40 9781
TEST_TIME 41 9686
TEST_TIME 42 9647
TEST_TIME 43 9661
TEST_TIME 44 9594
TEST_TIME 45 9485
TEST_TIME 46 9442
TEST_TIME 47 9315
TEST_TIME 48 9372
TEST_TIME 49 9512
TEST_TIME 50 9412
TEST_TIME 51 9366
TEST_TIME 52 9555
TEST_TIME 53 9472
TEST_TIME 54 9565
TEST_TIME 55 9454
TEST_TIME 56 9545
TEST_TIME 57 9507
TEST_TIME 58 9563
TEST_TIME 59 9565
TEST_TIME 60 9666
TEST_TIME 61 9561
TEST_TIME 62 9435
TEST_TIME 63 9338
TEST_TIME 64 9346
TEST_TIME 65 9290
TEST_TIME 66 9244
TEST_TIME 67 9204
TEST_TIME 68 9138
TEST_TIME 69 9135
TEST_TIME 70 9038
TEST_TIME 71 9145
TEST_TIME 72 9139
TEST_TIME 73 9155
TEST_TIME 74 9194
TEST_TIME 75 9186
TEST_TIME 76 9178
TEST_TIME 77 9199
TEST_TIME 78 9184
TEST_TIME 79 9271
TEST_TIME 80 9417
TEST_TIME 81 9353
TEST_TIME 82 9403
TEST_TIME 83 9401
TEST_TIME 84 9411
TEST_TIME 85 9308
TEST_TIME 86 9275
TEST_TIME 87 9245
TEST_TIME 88 9184
TEST_TIME 89 9135
TEST_TIME 90 9127
TEST_TIME 91 9118
TEST_TIME 92 9047
TEST_TIME 93 9111
TEST_TIME 94 9104
TEST_TIME 95 9198
TEST_TIME 96 9151
TEST_TIME 97 9166
TEST_TIME 98 9169
TEST_TIME 99 9174
TEST_TIME 100 9222
TEST_TIME 101 9212
TEST_TIME 102 9223
TEST_TIME 103 9238
TEST_TIME 104 9207
TEST_TIME 105 9131
TEST_TIME 106 9155
TEST_TIME 107 9134
TEST_TIME 108 9080
TEST_TIME 109 9053
TEST_TIME 110 9024
TEST_TIME 111 9010
TEST_TIME 112 8988
TEST_TIME 113 9043
TEST_TIME 114 9032
TEST_TIME 115 8998
TEST_TIME 116 9019
TEST_TIME 117 9085
TEST_TIME 118 9074
TEST_TIME 119 9061
TEST_TIME 120 8995

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-12-2021 13:38:03 to 17-12-2021 13:38:07.

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 1501715 1501715 0 0.00 0 21647 7 0 0
doc_list_biud 1501715 1501715 0 0.00 0 83114 30 0 0
sp_add_invoice_to_stock 5134 5005 129 2.51 27 274389 18458 0 129
sp_cancel_adding_invoice 5460 5460 0 0.00 6 73824 827 0 0
sp_cancel_client_order 1768 1768 0 0.00 5 10766 352 0 0
sp_cancel_customer_reserve 535968 535968 0 0.00 1 19570 65 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 3745 90 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 6115 68 0 0
sp_cancel_supplier_invoice 6062 6047 15 0.24 2 83118 900 0 15
sp_cancel_supplier_order 382 382 0 0.00 5 1119270 114795 0 0
sp_cancel_write_off 9971 9971 0 0.00 1 12572 117 0 0
sp_client_order 20389 20389 0 0.00 4 43597 386 0 0
sp_customer_reserve 457751 457751 0 0.00 0 54843 182 0 0
sp_fill_shopping_cart 31315 31315 0 0.00 1 42066 54 0 0
sp_fill_shopping_cart_clo_res 457743 457743 0 0.00 0 15057 17 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 32073 466 0 0
sp_kill_cost_storno 16552 16552 0 0.00 0 12559 57 0 0
sp_kill_qstorno_ret_qs2qd 1088336 1088336 0 0.00 0 83113 35 0 0
sp_kill_qty_storno 574701 574701 0 0.00 0 83113 76 0 0
sp_lock_selected_doc 21018 21018 0 0.00 0 10944 6 0 0
sp_make_cost_storno 28192 28192 0 0.00 0 17850 60 0 0
sp_multiply_rows_for_pdistr 28192 28192 0 0.00 0 11053 35 0 0
sp_multiply_rows_for_qdistr 20389 20389 0 0.00 0 43582 194 0 0
sp_pay_from_customer 7114 7114 0 0.00 4 20069 144 0 0
sp_pay_to_supplier 5973 5973 0 0.00 5 21652 156 0 0
sp_payment_common 13087 13087 0 0.00 1 11272 33 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 10730 318 0 0
sp_qd_handle_on_invoice_upd_sts 10456 10456 0 0.00 0 46151 379 0 0
sp_qd_handle_on_reserve_upd_sts 10109 10109 0 0.00 0 14916 62 0 0
sp_reserve_write_off 10109 10109 0 0.00 7 20279 193 0 0
sp_supplier_invoice 5501 5501 0 0.00 6 125737 3718 0 0
sp_supplier_order 5433 5433 0 0.00 5 39030 1314 0 0
srv_aggregate_perf_data 115 115 0 0.00 2900 130869 13087 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 946 154234 52106 0 0
srv_find_qd_qs_mism 1031112 1031112 0 0.00 0 10316 6 0 0
srv_make_invnt_saldo 463 463 0 0.00 42 80655 3445 0 0
srv_make_money_saldo 729 729 0 0.00 1 12648 543 0 0
srv_recalc_idx_stat 5 5 0 0.00 120077 498115 321438 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 5005 5005 0 0.00 0 12908 164 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 2926 113 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 4139 13 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 813 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 790 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1081 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 2701 22 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 19242 1327 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 2135 9 0 0
t$perf-norm:sp_client_order 20389 20389 0 0.00 0 7177 16 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 4023 10 0 0
t$perf-norm:sp_pay_from_customer 7114 7114 0 0.00 0 3847 8 0 0
t$perf-norm:sp_pay_to_supplier 5973 5973 0 0.00 0 7792 10 0 0
t$perf-norm:sp_reserve_write_off 10109 10109 0 0.00 0 11399 14 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 10156 21 0 0
t$perf-norm:sp_supplier_order 5433 5433 0 0.00 0 7786 18 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 406 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 3340 64 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 992 10 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 595 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 23 6 0 0
v_add_invoice_to_stock 10250 9992 258 2.51 0 5361 20 0 258
v_all_customers 36944 36944 0 0.00 0 32 1 0 0
v_all_suppliers 30384 30384 0 0.00 0 58 1 0 0
v_all_wares 40778 40778 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 3342 10 0 0
v_cancel_client_order 3536 3536 0 0.00 0 7172 7 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3303 10 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 2434 5 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1485 5 0 0
v_cancel_supplier_invoice 882 852 30 3.40 0 5861 25 0 30
v_cancel_supplier_order 764 764 0 0.00 0 5449 14 0 0
v_cancel_write_off 2114 2114 0 0.00 0 4446 16 0 0
v_max_id_clo_ord 5433 5433 0 0.00 0 393 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 3590 13 0 0
v_max_id_ord_sup 5493 5493 0 0.00 0 9483 11 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 792 3 0 0
v_max_non_paid_realizn 3869 3869 0 0.00 0 6173 6 0 0
v_min_id_clo_ord 5433 5433 0 0.00 0 5692 9 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 2505 6 0 0
v_min_id_ord_sup 5493 5493 0 0.00 0 7465 8 0 0
v_min_non_paid_invoice 5973 5973 0 0.00 0 7343 23 0 0
v_min_non_paid_realizn 7114 7114 0 0.00 0 9677 26 0 0
v_our_firm 10324 10324 0 0.00 0 23 1 0 0
v_reserve_write_off 20218 20218 0 0.00 0 7274 7 0 0
x_lock_depdocs_on_canc_invoice 11081 11081 0 0.00 0 17312 116 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 7689 355 0 0
x_make_qstorno_1000_1200 5433 5433 0 0.00 2 35533 1237 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 3 125621 3488 0 0
x_make_qstorno_2100_3300 457751 457751 0 0.00 0 44695 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2021 13:38:08 to 17-12-2021 13:38:12.

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:14 959 8192000000 8957725824 9020518400 3901568 0.047 2 1 2 0 3999184 61840 35776
11:08:17 40069 8192000000 9732939376 10030149632 806598496 8.963 100 91 182 0 837461376 8714128 22148752
11:10:04 14665 8192000000 9758924896 10063777792 831931440 9.219 100 86 171 1 865362976 12927408 20504128
11:11:26 15423 8192000000 9809034608 10128859136 869924464 9.599 100 85 170 0 911409728 21321904 20163360
11:12:47 11887 8192000000 9871773248 10206871552 924194288 10.137 100 81 162 0 983154624 41366048 17594288
11:14:07 6407 8192000000 9929279632 10272579584 961289104 10.502 100 82 164 0 1044459424 66786992 16383328
11:15:16 5395 8192000000 9975494496 10336608256 997688176 10.856 100 71 139 1 1085255984 73317104 14250704
11:16:26 2448 8192000000 10001885680 10367098880 1032962752 11.197 100 86 172 1 1117130496 66850144 17317600
11:17:30 1778 8192000000 10038026992 10405527552 1058539184 11.442 100 94 187 2 1147114496 68840176 19735136
11:18:32 969 8192000000 10059929616 10434076672 1073705776 11.587 100 93 188 2 1170526144 77747792 19072576
11:19:34 2595 8192000000 10037872672 10428141568 1080429776 11.652 100 60 122 0 1154537456 62206048 11901632
11:20:38 8117 8192000000 9986953472 10382327808 1086283776 11.707 100 36 73 1 1110406368 16675664 7446928
11:21:51 8146 8192000000 10000794688 10402811904 1085678240 11.702 100 23 46 0 1116259616 26035584 4545792
11:23:02 52340 8192000000 10000592144 10410749952 1091598976 11.758 100 25 50 0 1116286912 19284032 5403904
11:24:56 8148 8192000000 10043308000 10454798336 1097472048 11.814 100 48 97 0 1155387520 47928960 9986512
11:26:06 4903 8192000000 10001944816 10393677824 1084915680 11.694 99 46 92 1 1116794736 21037856 10841200
11:27:11 7586 8192000000 9988260832 10381856768 1064014160 11.495 98 29 55 1 1085338160 16142096 5181904
11:28:24 21101 8192000000 9995212512 10397495296 1048668320 11.348 98 26 52 1 1083564912 29441456 5455136
11:29:46 6520 8192000000 9948113136 10331545600 1018521024 11.058 98 24 48 2 1041299440 17068416 5710000
11:30:53 7080 8192000000 9941217776 10323709952 1008786720 10.964 98 46 92 0 1043282368 23491184 11004464
11:32:01 61922 8192000000 9949873760 10343493632 1022047104 11.092 99 28 55 0 1041995760 14412640 5536016
11:34:03 5898 8192000000 9959569072 10350112768 1032125360 11.189 99 52 104 2 1071665984 28308992 11231632
11:35:10 6151 8192000000 9980676432 10373070848 1056271120 11.421 100 59 118 1 1087188432 17084448 13832864
11:36:20 6228 8192000000 9989471120 10389340160 1067515696 11.528 100 40 79 1 1091748992 15186640 9046656
11:37:27 93325 8192000000 10009660704 10411769856 1078600656 11.634 100 33 66 1 1116870752 31233152 7036944
11:40:07 22419 8192000000 10083770640 10497060864 1101108880 11.848 100 50 100 0 1210098656 98344128 10645648
11:41:36 18590 8192000000 10093676224 10505854976 1114832112 11.978 100 47 94 1 1209492048 85044880 9615056
11:43:03 20325 8192000000 10117874752 10526220288 1129238704 12.114 100 44 86 0 1227221264 88256800 9725760
11:44:37 37091 8192000000 10167337536 10587045888 1128915232 12.111 99 38 76 1 1271718288 133440736 9362320
11:46:18 6278 8192000000 10252477296 10651275264 1149364464 12.304 100 77 154 0 1360896512 193796032 17736016
11:47:29 5471 8192000000 10292225728 10696781824 1158726512 12.391 100 60 119 3 1398173696 226514416 12932768
11:48:37 2490 8192000000 10272762496 10675482624 1144374128 12.257 99 87 174 1 1368647712 203935680 20337904
11:49:45 3178 8192000000 10261852896 10699862016 1148671024 12.297 98 81 158 3 1367303360 200558352 18073984
11:50:54 2099 8192000000 10271284720 10667495424 1152264304 12.331 100 89 172 1 1372370496 200879024 19227168
11:51:58 2483 8192000000 10295347584 10694004736 1146399824 12.276 99 75 149 0 1395409152 232750304 16259024
11:53:07 2047 8192000000 10336590128 10731622400 1132545936 12.145 99 86 170 1 1434919168 282739488 19633744
11:54:12 940 8192000000 10360048864 10760318976 1115732656 11.987 100 95 187 0 1452561536 314908592 21920288
11:55:17 2989 8192000000 10371531968 10769788928 1122348256 12.049 100 73 146 2 1466751264 326729264 17673744
11:56:24 2692 8192000000 10308567408 10731012096 1124452944 12.069 100 69 138 0 1415981856 275166704 16362208
11:57:33 5082 8192000000 10325936608 10733613056 1124465200 12.069 100 54 108 1 1426142288 288400848 13276240
11:58:39 5824 8192000000 10371801312 10781777920 1134751952 12.166 100 44 88 0 1460447440 314894096 10801392
11:59:51 18004 8192000000 10381612912 10810626048 1143234656 12.246 100 31 62 0 1483034832 331642256 8157920
12:01:12 10449 8192000000 10363093072 10788192256 1118717296 12.015 99 32 63 0 1448344432 321580640 8046496
12:02:24 29595 8192000000 10293137872 10761375744 1096937104 11.809 98 21 42 0 1380621152 277995280 5688768
12:04:15 50941 8192000000 10077063088 10500259840 1075183504 11.602 97 30 60 1 1149528400 67949312 6395584
12:06:17 27344 8192000000 10059128624 10484486144 1093913632 11.780 98 62 124 1 1135424400 28854336 12656432
12:08:02 52801 8192000000 10077111840 10546601984 1117058288 11.999 100 5 10 0 1170528624 52504736 965600
12:10:06 70453 8192000000 10116409328 10558476288 1132522880 12.145 100 18 37 1 1208709360 72266704 3919776
12:12:28 61142 8192000000 10125966864 10593759232 1129512160 12.117 99 23 46 0 1246015504 111430544 5072800
12:14:30 130902 8192000000 10074165680 10517241856 1119352848 12.021 98 27 53 0 1161893136 37176208 5364080
12:17:46 33531 8192000000 10101462240 10552160256 1149253152 12.302 100 13 26 0 1196846352 44898848 2694352
12:19:21 20676 8192000000 10116729456 10557116416 1154476272 12.351 100 43 78 2 1215951920 53098720 8376928
12:20:45 78890 8192000000 10100612800 10556067840 1139340512 12.209 99 19 38 0 1209332304 66054928 3936864
12:23:47 71855 8192000000 10147559776 10597130240 1139103696 12.207 99 31 62 0 1227761312 81936240 6721376
12:26:00 104813 8192000000 10078044144 10520543232 1113484656 11.965 97 8 16 0 1157737088 42788560 1463872
12:28:47 91298 8192000000 10093230736 10592378880 1147096976 12.282 100 20 40 0 1246414448 95201280 4116192
12:31:18 53043 8192000000 10082560976 10546143232 1133824720 12.157 99 10 20 0 1178430032 42776528 1828784
12:33:11 51974 8192000000 10099136496 10565853184 1138146208 12.198 99 33 66 1 1223582208 78631376 6804624
12:35:04 117954 8192000000 10111322400 10607521792 1092759216 11.769 96 16 32 0 1133920336 38412960 2748160
12:38:03 142446 8192000000 10045874800 10483429376 1077693888 11.625 95 15 29 1 1114772608 34316864 2761856
12:41:26 49684 8192000000 10041469680 10484826112 1091443648 11.756 98 32 63 1 1148055552 50294480 6317424
12:43:26 66992 8192000000 10057774480 10489290752 1099322464 11.831 98 14 25 2 1149917920 48084448 2511008
12:45:34 106188 8192000000 10050245520 10526011392 1085286096 11.698 97 11 22 0 1121315792 33707632 2322064
12:48:20 45524 8192000000 10039905408 10492174336 1098537984 11.824 99 16 32 2 1146487936 44253840 3696112
12:50:13 51837 8192000000 10059693536 10518827008 1086008896 11.705 98 32 64 0 1156256352 63624128 6623328
12:52:18 24112 8192000000 10057065760 10477383680 1089242544 11.735 98 38 76 0 1132023616 35028576 7752496
12:53:42 32063 8192000000 10032491008 10472042496 1069314944 11.546 96 22 44 0 1131170592 57307072 4548576
12:55:17 72299 8192000000 10135825536 10597093376 1124158352 12.066 100 25 50 0 1247233840 117545952 5529536
12:57:36 52271 8192000000 10057312672 10481074176 1102625440 11.863 98 18 36 0 1141018720 35181376 3211904
12:59:42 39841 8192000000 10106866288 10546294784 1129554608 12.117 100 10 20 0 1208584048 77041792 1987648
13:01:29 154232 8192000000 10058761392 10497048576 1098480848 11.823 97 27 54 0 1134375520 30431248 5463424
13:05:04 80752 8192000000 10081003920 10542166016 1101780352 11.855 97 12 22 1 1175583104 71304688 2498064
13:07:45 84662 8192000000 10118590736 10591420416 1138828944 12.205 100 17 35 1 1222322704 79451456 4042304
13:10:23 55623 8192000000 10139181440 10631020544 1142789744 12.242 100 16 32 0 1258359040 111803728 3765568
13:12:19 116975 8192000000 10119005632 10564816896 1136094288 12.179 99 12 24 1 1190841904 52391968 2355648
13:15:27 56094 8192000000 10094398528 10528358400 1111210256 11.944 97 25 50 1 1173108464 56716832 5181376
13:17:26 63530 8192000000 10089043712 10573373440 1124081824 12.066 98 14 29 0 1164992992 38361568 2549600
13:19:34 85028 8192000000 10095985568 10540883968 1105846304 11.893 96 37 74 0 1178162384 65089040 7227040
13:22:03 135321 8192000000 10084843520 10595270656 1134585680 12.165 99 26 52 1 1219466144 78598624 6281840
13:26:54 65669 8192000000 10051829616 10496086016 1090863232 11.751 96 11 22 1 1116929504 23225936 2840336
13:29:16 48526 8192000000 10113793760 10561785856 1123061776 12.056 99 27 54 1 1203755584 75011632 5682176
13:31:16 56506 8192000000 10079234800 10531233792 1126475712 12.088 99 25 50 0 1172440096 40558432 5405952
13:33:28 85252 8192000000 10076585568 10593517568 1077413712 11.623 96 18 36 0 1177115568 95950368 3751488
13:36:05 45851 8192000000 10053360288 10478620672 1097803344 11.817 100 31 62 0 1138794512 35195008 5796160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-12-2021 13:38:13 to 17-12-2021 13:38:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 17-12-2021 13:38:29 to 17-12-2021 13:38:31.

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          239203
MON$OLDEST_ACTIVE               241699
MON$OLDEST_SNAPSHOT             241699
MON$NEXT_TRANSACTION            241699
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                       2037856
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             815
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2691 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2691 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2691 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2691 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 ---
:56: 
:57: mscs-test-161.budzdorov.ru    Fri Dec 17 13:37:14 2021
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
--- end of diff output ---

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

17.12.21 13:38:32. 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
2021-12-17 13:38:34 No severe PSQL-related problems occured
17.12.21 13:38:36. End of report.