Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.01.2021 07: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16786608 kB
MemAvailable 31274404 kB
Buffers 0 kB
Cached 14873996 kB
SwapCached 4868 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.0
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-01-24 07:36:04
DTS_END                         2021-01-24 07:36:04.4090
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/35318
TRN_ID                          225610
ATT_ID                          671
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: 8794.54 20382 1055345
customer order: creation 159.82 422 19179
customer order: refuse 13.84 324 1661
order to supplier: creation 43.48 1411 5217
order to supplier: removal 3.30 123832 396
invoice (draft): creation 43.98 3307 5278
invoice (draft): removal 50.76 863 6091
invoice accept: apply 38.41 16215 4609
invoice accept: cancel 45.51 802 5461
customer reserve: creation 3626.13 153 435136
customer reserve: removal 4480.90 73 537708
realization accept: apply 77.88 163 9345
realization accept: cancel 89.45 115 10734
payment from customer: creation 56.16 102 6739
payment from customer: removal 5.19 88 623
payment to supplier: creation 46.88 128 5625
payment to supplier: removal 3.12 80 374
service: total inventory turnovers 3.52 3386 422
service: total monetary turnovers 5.24 614 629
service: aggregate perf. data 0.94 13745 113
service: refresh index statistics 0.04 241823 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-01-2021 07:37:23 to 24-01-2021 07:37:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3820
TEST_TIME 2 4643
TEST_TIME 3 5170
TEST_TIME 4 5558
TEST_TIME 5 5440
TEST_TIME 6 5158
TEST_TIME 7 5382
TEST_TIME 8 6043
TEST_TIME 9 5863
TEST_TIME 10 5828
TEST_TIME 11 6144
TEST_TIME 12 6514
TEST_TIME 13 6914
TEST_TIME 14 7797
TEST_TIME 15 7630
TEST_TIME 16 7909
TEST_TIME 17 9098
TEST_TIME 18 9031
TEST_TIME 19 9508
TEST_TIME 20 9534
TEST_TIME 21 9396
TEST_TIME 22 9848
TEST_TIME 23 10435
TEST_TIME 24 10444
TEST_TIME 25 11041
TEST_TIME 26 11950
TEST_TIME 27 11534
TEST_TIME 28 11304
TEST_TIME 29 11308
TEST_TIME 30 11120
TEST_TIME 31 10921
TEST_TIME 32 10776
TEST_TIME 33 10597
TEST_TIME 34 10480
TEST_TIME 35 10390
TEST_TIME 36 10200
TEST_TIME 37 9981
TEST_TIME 38 9880
TEST_TIME 39 9851
TEST_TIME 40 9795
TEST_TIME 41 9783
TEST_TIME 42 9687
TEST_TIME 43 9691
TEST_TIME 44 9667
TEST_TIME 45 9590
TEST_TIME 46 9613
TEST_TIME 47 9688
TEST_TIME 48 9710
TEST_TIME 49 9632
TEST_TIME 50 9738
TEST_TIME 51 9748
TEST_TIME 52 9635
TEST_TIME 53 9554
TEST_TIME 54 9465
TEST_TIME 55 9347
TEST_TIME 56 9444
TEST_TIME 57 9372
TEST_TIME 58 9314
TEST_TIME 59 9264
TEST_TIME 60 9265
TEST_TIME 61 9287
TEST_TIME 62 9311
TEST_TIME 63 9246
TEST_TIME 64 9436
TEST_TIME 65 9358
TEST_TIME 66 9366
TEST_TIME 67 9511
TEST_TIME 68 9468
TEST_TIME 69 9605
TEST_TIME 70 9482
TEST_TIME 71 9366
TEST_TIME 72 9289
TEST_TIME 73 9231
TEST_TIME 74 9178
TEST_TIME 75 9148
TEST_TIME 76 9126
TEST_TIME 77 9042
TEST_TIME 78 9002
TEST_TIME 79 8994
TEST_TIME 80 8984
TEST_TIME 81 9026
TEST_TIME 82 9020
TEST_TIME 83 8967
TEST_TIME 84 9017
TEST_TIME 85 9013
TEST_TIME 86 8993
TEST_TIME 87 9133
TEST_TIME 88 9151
TEST_TIME 89 9227
TEST_TIME 90 9201
TEST_TIME 91 9106
TEST_TIME 92 9051
TEST_TIME 93 8987
TEST_TIME 94 8963
TEST_TIME 95 8921
TEST_TIME 96 8947
TEST_TIME 97 8933
TEST_TIME 98 8920
TEST_TIME 99 8912
TEST_TIME 100 8928
TEST_TIME 101 8914
TEST_TIME 102 8950
TEST_TIME 103 8936
TEST_TIME 104 9006
TEST_TIME 105 9040
TEST_TIME 106 9077
TEST_TIME 107 9067
TEST_TIME 108 9112
TEST_TIME 109 9066
TEST_TIME 110 8988
TEST_TIME 111 8947
TEST_TIME 112 8904
TEST_TIME 113 8866
TEST_TIME 114 8874
TEST_TIME 115 8842
TEST_TIME 116 8865
TEST_TIME 117 8859
TEST_TIME 118 8849
TEST_TIME 119 8869
TEST_TIME 120 8795

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-01-2021 07:37:26 to 24-01-2021 07:37:30.

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 1458226 1458226 0 0.00 0 14018 6 0 0
doc_list_biud 1458222 1458222 0 0.00 0 70687 33 0 0
sp_add_invoice_to_stock 4692 4609 83 1.76 31 151272 16215 0 83
sp_cancel_adding_invoice 5461 5461 0 0.00 5 47298 803 0 0
sp_cancel_client_order 1661 1661 0 0.00 4 13970 325 0 0
sp_cancel_customer_reserve 537708 537708 0 0.00 1 70688 74 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 6509 88 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 2 3679 80 0 0
sp_cancel_supplier_invoice 6105 6091 14 0.22 1 28489 863 0 14
sp_cancel_supplier_order 396 396 0 0.00 6 1184468 123833 0 0
sp_cancel_write_off 10734 10734 0 0.00 1 17312 115 0 0
sp_client_order 19179 19179 0 0.00 4 22063 423 0 0
sp_customer_reserve 435136 435136 0 0.00 0 42301 154 0 0
sp_fill_shopping_cart 29658 29658 0 0.00 1 16605 45 0 0
sp_fill_shopping_cart_clo_res 435134 435134 0 0.00 0 20986 18 0 0
sp_get_clo_for_invoice 4607 4607 0 0.00 0 36854 455 0 0
sp_kill_cost_storno 17192 17192 0 0.00 0 9182 56 0 0
sp_kill_qstorno_ret_qs2qd 1092637 1092637 0 0.00 0 70683 39 0 0
sp_kill_qty_storno 576002 576002 0 0.00 0 70687 83 0 0
sp_lock_selected_doc 19275 19275 0 0.00 0 6575 5 0 0
sp_make_cost_storno 26316 26316 0 0.00 0 13792 50 0 0
sp_multiply_rows_for_pdistr 26313 26313 0 0.00 0 9497 32 0 0
sp_multiply_rows_for_qdistr 19179 19179 0 0.00 0 22045 253 0 0
sp_pay_from_customer 6739 6739 0 0.00 4 9509 103 0 0
sp_pay_to_supplier 5625 5625 0 0.00 5 14086 129 0 0
sp_payment_common 12361 12361 0 0.00 1 9498 22 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 13953 299 0 0
sp_qd_handle_on_invoice_upd_sts 10067 10067 0 0.00 0 20762 358 0 0
sp_qd_handle_on_reserve_upd_sts 9345 9345 0 0.00 0 6314 45 0 0
sp_reserve_write_off 9345 9345 0 0.00 7 13812 164 0 0
sp_supplier_invoice 5278 5278 0 0.00 6 76809 3307 0 0
sp_supplier_order 5217 5217 0 0.00 6 26722 1412 0 0
srv_aggregate_perf_data 113 113 0 0.00 2880 118353 13745 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1004 122491 51285 0 0
srv_find_qd_qs_mism 1010442 1010442 0 0.00 0 10331 5 0 0
srv_make_invnt_saldo 422 422 0 0.00 49 89384 3386 0 0
srv_make_money_saldo 629 629 0 0.00 0 17580 615 0 0
srv_recalc_idx_stat 5 5 0 0.00 113660 386075 241824 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4609 4609 0 0.00 0 17283 153 0 0
t$perf-norm:sp_cancel_adding_invoice 355 355 0 0.00 0 4303 64 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 8867 15 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 1277 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 6007 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1442 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1370 22 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 17090 1414 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 3535 10 0 0
t$perf-norm:sp_client_order 19179 19179 0 0.00 0 9156 18 0 0
t$perf-norm:sp_customer_reserve 894 894 0 0.00 0 2232 7 0 0
t$perf-norm:sp_pay_from_customer 6739 6739 0 0.00 0 13720 10 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 13395 17 0 0
t$perf-norm:sp_reserve_write_off 9345 9345 0 0.00 0 4022 9 0 0
t$perf-norm:sp_supplier_invoice 5278 5278 0 0.00 0 5798 15 0 0
t$perf-norm:sp_supplier_order 5217 5217 0 0.00 0 5456 14 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 203 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 455 9 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 3178 18 0 0
t$perf-norm:srv_make_money_saldo 629 629 0 0.00 0 2174 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9374 9208 166 1.77 0 5220 16 0 166
v_all_customers 35150 35150 0 0.00 0 17 1 0 0
v_all_suppliers 29350 29350 0 0.00 0 18 1 0 0
v_all_wares 38358 38358 0 0.00 0 281 1 0 0
v_cancel_adding_invoice 710 710 0 0.00 0 1064 7 0 0
v_cancel_client_order 3322 3322 0 0.00 0 2249 4 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 1731 7 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 1347 7 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 2642 9 0 0
v_cancel_supplier_invoice 790 762 28 3.54 0 2582 16 0 28
v_cancel_supplier_order 792 792 0 0.00 0 5117 13 0 0
v_cancel_write_off 1848 1848 0 0.00 0 2259 13 0 0
v_max_id_clo_ord 5212 5212 0 0.00 0 1603 1 0 0
v_max_id_clo_res 894 894 0 0.00 0 6804 14 0 0
v_max_id_ord_sup 5266 5266 0 0.00 0 7105 6 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 50 2 0 0
v_max_non_paid_realizn 3518 3518 0 0.00 0 4320 3 0 0
v_min_id_clo_ord 5212 5212 0 0.00 0 2165 6 0 0
v_min_id_clo_res 894 894 0 0.00 0 6778 19 0 0
v_min_id_ord_sup 5266 5266 0 0.00 0 6410 7 0 0
v_min_non_paid_invoice 5624 5624 0 0.00 0 5840 17 0 0
v_min_non_paid_realizn 6737 6737 0 0.00 0 6778 14 0 0
v_our_firm 9646 9646 0 0.00 0 18 1 0 0
v_reserve_write_off 18688 18688 0 0.00 0 5057 6 0 0
x_lock_depdocs_on_canc_invoice 11171 11171 0 0.00 0 21782 115 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 30529 410 0 0
x_make_qstorno_1000_1200 5217 5217 0 0.00 2 26705 1333 0 0
x_make_qstorno_1200_2000 5278 5278 0 0.00 3 76364 3140 0 0
x_make_qstorno_2100_3300 435136 435136 0 0.00 0 42299 134 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-01-2021 07:37:31 to 24-01-2021 07:37:35.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:06:08 3 8192000000 8800579488 8812552192 6224480 0.075 2 2 3 0 6389744 103904 61360
05:07:09 22739 8192000000 9723794720 10019213312 797421504 8.870 100 88 176 0 827030272 8018480 21590288
05:08:41 28565 8192000000 9760113168 10065383424 833510560 9.235 100 91 182 0 870306448 14811552 21984336
05:10:21 19180 8192000000 9822377376 10141372416 871834448 9.618 100 85 170 0 925917328 34894720 19188160
05:11:42 9619 8192000000 9867558944 10201182208 913195360 10.029 100 81 163 0 975682368 45227408 17259600
05:12:57 6176 8192000000 9931677952 10274627584 951474896 10.406 100 77 154 1 1045440160 78109312 15855952
05:14:08 4293 8192000000 9945212944 10303275008 990129440 10.783 100 74 147 2 1068466832 63394880 14942512
05:15:15 1623 8192000000 10008071792 10368233472 1022000128 11.091 100 85 170 0 1117292192 78660624 16631440
05:16:19 1907 8192000000 10037608288 10404950016 1049935840 11.360 100 91 181 0 1146453616 78399312 18118464
05:17:24 989 8192000000 10034319600 10405961728 1064924048 11.504 100 98 196 1 1146616272 61398368 20293856
05:18:27 6120 8192000000 10003397584 10403868672 1066386816 11.518 100 48 95 1 1139112736 63453456 9272464
05:19:34 10556 8192000000 10003370896 10392735744 1073266576 11.583 100 37 73 2 1109790480 29705824 6818080
05:20:45 41744 8192000000 9994605264 10396233728 1081626096 11.663 100 39 76 0 1112794064 22973552 8194416
05:22:28 4465 8192000000 10013830320 10400808960 1088094384 11.725 100 47 90 2 1115112528 16752784 10265360
05:23:34 7653 8192000000 10017907392 10424754176 1090462528 11.747 100 23 44 5 1120941888 25747968 4731392
05:24:45 60723 8192000000 10025414704 10415124480 1088779264 11.731 100 45 90 2 1127335392 28570560 9985568
05:26:47 7661 8192000000 10017494672 10422063104 1068977488 11.542 99 49 98 0 1109501456 30113936 10410032
05:27:56 4626 8192000000 9954187360 10334089216 1023028416 11.101 96 45 90 1 1048064784 14772080 10264288
05:29:01 12521 8192000000 9914154880 10303287296 958819440 10.477 93 29 58 1 977426416 12253328 6353648
05:30:16 17566 8192000000 9900469952 10289655808 959910560 10.488 95 27 54 1 1014088304 48349056 5828688
05:31:36 10837 8192000000 9944154896 10334212096 1028317328 11.152 100 36 72 1 1058638640 22306640 8014672
05:32:47 7544 8192000000 9953125360 10334855168 1035177888 11.218 100 31 62 2 1069547504 27086016 7283600
05:33:58 8629 8192000000 9972616400 10362834944 1056274192 11.421 100 25 51 1 1076663568 14595824 5793552
05:35:12 49482 8192000000 9969129184 10376413184 1060036624 11.457 100 22 44 0 1092253072 26914480 5301968
05:37:12 26420 8192000000 10019197376 10417201152 1096187504 11.801 100 45 87 2 1145773392 40519968 9065920
05:38:43 14157 8192000000 10109142448 10500653056 1124121568 12.066 100 64 128 3 1213097520 75977664 12998288
05:40:04 7617 8192000000 10150230624 10543337472 1140925376 12.224 100 61 122 0 1262302672 107758144 13619152
05:41:15 10926 8192000000 10173356880 10580987904 1147697808 12.288 100 39 76 0 1282305520 126592192 8015520
05:42:40 9548 8192000000 10241093712 10641162240 1152280768 12.331 100 57 113 1 1343814528 179065344 12468416
05:43:52 5709 8192000000 10284749232 10692550656 1159425808 12.398 100 57 111 1 1390869648 218925744 12518096
05:45:01 3383 8192000000 10272553392 10680811520 1167318336 12.472 100 60 120 3 1382883968 202118880 13446752
05:46:11 2074 8192000000 10325783056 10731286528 1176157856 12.554 100 76 148 3 1433271168 240857360 16255952
05:47:16 1670 8192000000 10374145376 10776711168 1168439984 12.482 99 85 170 0 1479668624 291870064 19358576
05:48:24 1003 8192000000 10421892832 10817859584 1162592480 12.428 99 93 186 1 1513580128 330506816 20480832
05:49:29 1816 8192000000 10421502960 10845155328 1176657936 12.559 100 80 159 1 1529391808 333361984 19371888
05:50:35 2106 8192000000 10440924128 10846220288 1170433776 12.501 100 77 153 1 1531280080 342916176 17930128
05:51:40 3110 8192000000 10421769936 10831847424 1167496208 12.473 100 60 120 0 1525049792 343239920 14313664
05:52:47 5435 8192000000 10452813520 10860445696 1168862464 12.486 100 81 161 0 1549551776 361582416 19106896
05:54:00 23779 8192000000 10363250160 10799108096 1162202512 12.424 99 37 74 1 1477979264 305999248 9777504
05:55:26 17878 8192000000 10348662144 10816020480 1134409488 12.163 97 31 62 0 1462693520 320201136 8082896
05:56:45 18767 8192000000 10391841328 10819403776 1145962320 12.272 99 37 74 1 1488001104 332319376 9719408
05:58:07 21461 8192000000 10377795936 10848841728 1150367712 12.313 99 37 74 2 1517901840 357312112 10222016
05:59:35 21631 8192000000 10372997936 10874470400 1145272448 12.265 100 24 48 0 1475340960 323756688 6311824
06:01:21 21174 8192000000 10289478064 10743275520 1090289600 11.745 98 19 38 0 1389207184 293401888 5515696
06:02:43 78996 8192000000 10055693808 10475282432 1077077664 11.620 97 24 46 2 1118487888 34894400 6515824
06:05:02 15004 8192000000 10089041040 10516140032 1110074304 11.933 100 38 76 0 1189191728 71096144 8021280
06:06:23 78933 8192000000 10020540432 10465165312 1064462464 11.499 95 10 20 0 1094367872 28119136 1786272
06:08:42 66529 8192000000 10059312048 10499997696 1113651728 11.967 99 19 38 0 1153637840 36374448 3611664
06:10:50 84657 8192000000 10056931600 10514329600 1129708576 12.119 100 20 39 1 1176819360 43386912 3723872
06:13:20 59881 8192000000 10037050240 10498908160 1107129200 11.905 98 17 34 1 1133425360 22677008 3619152
06:15:22 53315 8192000000 10077776448 10553581568 1132837120 12.148 100 28 56 0 1184170336 45113472 6219744
06:17:33 51521 8192000000 10071938064 10512850944 1117677648 12.005 98 17 34 1 1156310640 34787808 3845184
06:19:37 50144 8192000000 10089831072 10528583680 1130372736 12.125 99 17 34 0 1183981072 50001536 3606800
06:21:27 21982 8192000000 10128481536 10547859456 1140274096 12.218 99 48 96 1 1207838144 57254832 10309216
06:23:24 86908 8192000000 10096232208 10566238208 1152751552 12.335 100 19 38 0 1230942208 73981248 4209408
06:25:59 77409 8192000000 10090111520 10593480704 1147010864 12.281 99 23 46 1 1216975968 65229616 4735488
06:28:19 101905 8192000000 10088835808 10560999424 1146693824 12.278 99 19 37 1 1207681024 56695552 4291648
06:33:09 77700 8192000000 10098931472 10565386240 1109990688 11.932 96 17 34 0 1155044272 41687968 3365616
06:35:27 65709 8192000000 10099625136 10544848896 1095913200 11.799 95 24 48 1 1174168544 73695312 4560032
06:37:45 52746 8192000000 10072281424 10532634624 1099136928 11.829 96 23 45 2 1162792512 59032480 4623104
06:39:54 89174 8192000000 10061363584 10523582464 1106348144 11.898 97 22 44 0 1190101664 78960848 4792672
06:42:24 103581 8192000000 10091814800 10567819264 1126942384 12.093 99 8 17 0 1182139808 53584048 1613376
06:45:07 103986 8192000000 10068802320 10573438976 1095166032 11.792 96 9 16 1 1143016960 45547840 2303088
06:47:52 102239 8192000000 10076288096 10538516480 1114250384 11.973 98 9 18 1 1167925328 51890160 1784784
06:50:36 61389 8192000000 10067063168 10548338688 1131735840 12.138 100 13 25 2 1185994928 51426208 2832880
06:52:37 79517 8192000000 10088749408 10558267392 1128260768 12.105 99 11 22 0 1160529104 30111104 2157232
06:55:20 51343 8192000000 10085744944 10534023168 1127806864 12.101 99 9 18 0 1197181568 67162416 2212288
06:57:27 58877 8192000000 10014433312 10455896064 1021824480 11.090 92 26 52 0 1067477520 39899200 5753840
06:59:34 88632 8192000000 10083208016 10536701952 1053718496 11.396 95 24 48 1 1137769648 78722928 5328224
07:02:02 76250 8192000000 10047527696 10560163840 1069627344 11.549 97 15 31 0 1186269456 113157200 3484912
07:04:19 96985 8192000000 10057463120 10560385024 1087147552 11.716 98 10 20 0 1135573680 46251840 2174288
07:07:00 102844 8192000000 10037573600 10478952448 1114054656 11.971 100 18 36 0 1151042576 33392576 3595344
07:09:51 54199 8192000000 10043326576 10518163456 1117786768 12.006 99 7 14 1 1159817296 40749536 1280992
07:11:46 73357 8192000000 10061613872 10543263744 1117922544 12.007 99 9 18 1 1182187344 62407344 1857456
07:14:00 51439 8192000000 10091636112 10521075712 1146147952 12.273 100 22 44 2 1182983504 32491216 4344336
07:15:52 49418 8192000000 10079529664 10531057664 1095488592 11.795 96 16 32 1 1142176880 43454896 3233392
07:17:51 61910 8192000000 10126285584 10587484160 1128012512 12.103 98 24 48 1 1197701184 64561360 5127312
07:19:53 89595 8192000000 10071448832 10588164096 1093187168 11.773 95 10 20 0 1207660496 112347216 2126112
07:22:29 57826 8192000000 10118090080 10598916096 1128818688 12.110 99 14 28 1 1181129824 49717200 2593936
07:24:34 122491 8192000000 10061513856 10499874816 1109125024 11.924 98 17 34 0 1134273648 21834480 3314144
07:28:00 86859 8192000000 10094874240 10560122880 1115122464 11.981 98 8 16 0 1194304368 77379824 1802080
07:30:27 52925 8192000000 10091366576 10576531456 1136637680 12.184 100 5 10 0 1183457760 45831168 988912
07:32:20 66110 8192000000 10117912512 10580467712 1132645744 12.146 99 24 48 1 1208584736 70719984 5219008
07:34:27 53006 8192000000 10130263840 10593599488 1108547936 11.919 98 25 49 3 1211018176 96868928 5601312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-01-2021 07:37:37 to 24-01-2021 07:37:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-01-2021 07:37:52 to 24-01-2021 07:37:54.

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          222000
MON$OLDEST_ACTIVE               225718
MON$OLDEST_SNAPSHOT             225718
MON$NEXT_TRANSACTION            225718
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                       2079584
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2341 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2341 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2341 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2341 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Jan 24 07:36:15 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

24.01.21 07:37:55. 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-01-24 07:37:58 No severe PSQL-related problems occured
24.01.21 07:37:59. End of report.